builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0224 starttime: 1464430048.42 results: success (0) buildid: 20160525152143 builduid: 8654ccbbea454f27b451ee4808dca30e revision: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:28.417336) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:28.417717) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:28.418181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:28.448944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:28.449209) ========= 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.waKKfxvPNc/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.e8UmImICZf/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-28 03:07:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 60.0M=0s 2016-05-28 03:07:28 (60.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.650778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:29.124384) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:29.124752) ========= 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.waKKfxvPNc/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.e8UmImICZf/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.025404 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:29.174397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:07:29.174751) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.waKKfxvPNc/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.e8UmImICZf/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-28 03:07:29,153 truncating revision to first 12 chars 2016-05-28 03:07:29,153 Setting DEBUG logging. 2016-05-28 03:07:29,153 attempt 1/10 2016-05-28 03:07:29,153 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4f7c3a2567f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 03:07:30,280 unpacking tar archive at: mozilla-aurora-4f7c3a2567f8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.413624 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:07:30.610478) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:30.610820) ========= script_repo_revision: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:30.611199) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:30.611477) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:07:30.628095) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-05-28 03:07:30.628416) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.waKKfxvPNc/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.e8UmImICZf/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:07:30 INFO - MultiFileLogger online at 20160528 03:07:30 in /builds/slave/test 03:07:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:07:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:07:30 INFO - {'append_to_log': False, 03:07:30 INFO - 'base_work_dir': '/builds/slave/test', 03:07:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:07:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:07:30 INFO - 'branch': 'Mozilla-Aurora', 03:07:30 INFO - 'buildbot_json_path': 'buildprops.json', 03:07:30 INFO - 'config_files': ('talos/mac_config.py',), 03:07:30 INFO - 'default_actions': ('clobber', 03:07:30 INFO - 'read-buildbot-config', 03:07:30 INFO - 'download-and-extract', 03:07:30 INFO - 'populate-webroot', 03:07:30 INFO - 'create-virtualenv', 03:07:30 INFO - 'install', 03:07:30 INFO - 'run-tests'), 03:07:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:07:30 INFO - 'download_minidump_stackwalk': True, 03:07:30 INFO - 'download_symbols': 'ondemand', 03:07:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:07:30 INFO - 'tooltool.py': '/tools/tooltool.py', 03:07:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:07:30 INFO - '/tools/misc-python/virtualenv.py')}, 03:07:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:07:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:07:30 INFO - 'installer_path': 'installer.exe', 03:07:30 INFO - 'log_level': 'info', 03:07:30 INFO - 'log_name': 'talos', 03:07:30 INFO - 'log_to_console': True, 03:07:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:07:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:07:30 INFO - 'opt_config_files': (), 03:07:30 INFO - 'pip_index': False, 03:07:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:07:30 INFO - 'cmd': ('bash', 03:07:30 INFO - '-c', 03:07:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:07:30 INFO - 'enabled': True, 03:07:30 INFO - 'halt_on_failure': False, 03:07:30 INFO - 'name': 'check_screen_resolution'},), 03:07:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:07:30 INFO - 'cmd': ('bash', 03:07:30 INFO - '-c', 03:07:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:07:30 INFO - 'enabled': True, 03:07:30 INFO - 'halt_on_failure': False, 03:07:30 INFO - 'name': 'check_screen_resolution'},), 03:07:30 INFO - 'run_cmd_checks_enabled': True, 03:07:30 INFO - 'sps_profile': False, 03:07:30 INFO - 'sps_profile_interval': 0, 03:07:30 INFO - 'suite': 'dromaeojs-e10s', 03:07:30 INFO - 'system_bits': '32', 03:07:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:07:30 INFO - 'title': 't-yosemite-r7-0224', 03:07:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:07:30 INFO - 'use_talos_json': True, 03:07:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:07:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:07:30 INFO - 'work_dir': 'build'} 03:07:30 INFO - ##### 03:07:30 INFO - ##### Running clobber step. 03:07:30 INFO - ##### 03:07:30 INFO - Running pre-action listener: _resource_record_pre_action 03:07:30 INFO - Running main action method: clobber 03:07:30 INFO - rmtree: /builds/slave/test/build 03:07:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:07:32 INFO - Running post-action listener: _resource_record_post_action 03:07:32 INFO - ##### 03:07:32 INFO - ##### Running read-buildbot-config step. 03:07:32 INFO - ##### 03:07:32 INFO - Running pre-action listener: _resource_record_pre_action 03:07:32 INFO - Running main action method: read_buildbot_config 03:07:32 INFO - Using buildbot properties: 03:07:32 INFO - { 03:07:32 INFO - "project": "", 03:07:32 INFO - "product": "firefox", 03:07:32 INFO - "script_repo_revision": "production", 03:07:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:07:32 INFO - "repository": "", 03:07:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:07:32 INFO - "buildid": "20160525152143", 03:07:32 INFO - "pgo_build": "False", 03:07:32 INFO - "basedir": "/builds/slave/test", 03:07:32 INFO - "buildnumber": 341, 03:07:32 INFO - "slavename": "t-yosemite-r7-0224", 03:07:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:07:32 INFO - "platform": "yosemite_r7", 03:07:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:07:32 INFO - "branch": "mozilla-aurora", 03:07:32 INFO - "repo_path": "releases/mozilla-aurora", 03:07:32 INFO - "revision": "4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3", 03:07:32 INFO - "stage_platform": "macosx64", 03:07:32 INFO - "builduid": "8654ccbbea454f27b451ee4808dca30e", 03:07:32 INFO - "slavebuilddir": "test" 03:07:32 INFO - } 03:07:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:07:32 INFO - Running post-action listener: _resource_record_post_action 03:07:32 INFO - ##### 03:07:32 INFO - ##### Running download-and-extract step. 03:07:32 INFO - ##### 03:07:32 INFO - Running pre-action listener: _resource_record_pre_action 03:07:32 INFO - Running main action method: download_and_extract 03:07:32 INFO - mkdir: /builds/slave/test/build/tests 03:07: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')]} 03:07:32 INFO - https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:07:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:07:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:07:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:07:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:07:40 INFO - Downloaded 1199 bytes. 03:07:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:07:40 INFO - Using the following test package requirements: 03:07:40 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:07:40 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:07:40 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:07:40 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'jsshell-mac.zip'], 03:07:40 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:07:40 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:07:40 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:07:40 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:07:40 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:07:40 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:07:40 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:07:40 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:07:40 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')]} 03:07:40 INFO - https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:07:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:07:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:07:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:07:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:07:44 INFO - Downloaded 18425352 bytes. 03:07:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:07:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:07:47 INFO - Return code: 0 03:07:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:47 INFO - https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:07:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:07:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:07:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:08:17 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 03:08:17 INFO - retry: attempt #1 caught exception: timed out 03:08:17 INFO - retry: Failed, sleeping 30 seconds before retrying 03:08:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #2 03:08:49 INFO - Downloaded 18425352 bytes. 03:08:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:08:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:08:52 INFO - Return code: 0 03:08: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')]} 03:08:52 INFO - https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:08:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:08:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:08:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:08:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:08:53 INFO - Downloaded 10840145 bytes. 03:08:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:08:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:08:54 INFO - Return code: 0 03:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:54 INFO - https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:08:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:08:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:08:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:08:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:08:57 INFO - Downloaded 114138629 bytes. 03:08:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:08:57 INFO - mkdir: /builds/slave/test/properties 03:08:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:08:57 INFO - Writing to file /builds/slave/test/properties/build_url 03:08:57 INFO - Contents: 03:08:57 INFO - build_url:https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:08:57 INFO - Running post-action listener: _resource_record_post_action 03:08:57 INFO - Running post-action listener: set_extra_try_arguments 03:08:57 INFO - ##### 03:08:57 INFO - ##### Running populate-webroot step. 03:08:57 INFO - ##### 03:08:57 INFO - Running pre-action listener: _resource_record_pre_action 03:08:57 INFO - Running main action method: populate_webroot 03:08:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:08:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:08:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:08:57 INFO - '--tppagecycles', 03:08:57 INFO - '7'], 03:08:57 INFO - 'tests': ['tsvgm']}}, 03:08:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:08:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:08:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:08:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:08:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:08:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:08:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['damp', 'tps']}, 03:08:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'tests': ['damp', 'tps']}, 03:08:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['dromaeo_dom']}, 03:08:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:08:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['a11yr', 03:08:57 INFO - 'ts_paint', 03:08:57 INFO - 'tpaint', 03:08:57 INFO - 'sessionrestore', 03:08:57 INFO - 'sessionrestore_no_auto_restore', 03:08:57 INFO - 'tabpaint']}, 03:08:57 INFO - 'other-e10s': {'tests': ['a11yr', 03:08:57 INFO - 'ts_paint', 03:08:57 INFO - 'tpaint', 03:08:57 INFO - 'sessionrestore', 03:08:57 INFO - 'sessionrestore_no_auto_restore', 03:08:57 INFO - 'tabpaint']}, 03:08:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:08:57 INFO - 'ts_paint', 03:08:57 INFO - 'tpaint', 03:08:57 INFO - 'sessionrestore', 03:08:57 INFO - 'sessionrestore_no_auto_restore', 03:08:57 INFO - 'tabpaint']}, 03:08:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:08:57 INFO - 'ts_paint', 03:08:57 INFO - 'tpaint', 03:08:57 INFO - 'sessionrestore', 03:08:57 INFO - 'sessionrestore_no_auto_restore', 03:08:57 INFO - 'tabpaint']}, 03:08:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['a11yr', 03:08:57 INFO - 'ts_paint', 03:08:57 INFO - 'tpaint', 03:08:57 INFO - 'sessionrestore', 03:08:57 INFO - 'sessionrestore_no_auto_restore', 03:08:57 INFO - 'tabpaint']}, 03:08:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['a11yr', 03:08:57 INFO - 'ts_paint', 03:08:57 INFO - 'tpaint', 03:08:57 INFO - 'sessionrestore', 03:08:57 INFO - 'sessionrestore_no_auto_restore', 03:08:57 INFO - 'tabpaint']}, 03:08:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['tsvgx', 03:08:57 INFO - 'tsvgr_opacity', 03:08:57 INFO - 'tart', 03:08:57 INFO - 'tscrollx', 03:08:57 INFO - 'cart']}, 03:08:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:08:57 INFO - 'tsvgr_opacity', 03:08:57 INFO - 'tart', 03:08:57 INFO - 'tscrollx', 03:08:57 INFO - 'cart']}, 03:08:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'talos_options': ['--disable-e10s'], 03:08:57 INFO - 'tests': ['tp5o']}, 03:08:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'tests': ['tp5o']}, 03:08:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'talos_options': ['--disable-e10s', 03:08:57 INFO - '--xperf_path', 03:08:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:08:57 INFO - 'tests': ['tp5n']}, 03:08:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:57 INFO - 'talos_options': ['--xperf_path', 03:08:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:08:57 INFO - 'tests': ['tp5n']}}, 03:08:57 INFO - 'talos.zip': {'path': '', 03:08:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:08:57 INFO - Running post-action listener: _resource_record_post_action 03:08:57 INFO - ##### 03:08:57 INFO - ##### Running create-virtualenv step. 03:08:57 INFO - ##### 03:08:57 INFO - Running pre-action listener: _resource_record_pre_action 03:08:57 INFO - Running main action method: create_virtualenv 03:08:57 INFO - Creating virtualenv /builds/slave/test/build/venv 03:08:57 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 03:08:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:08:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:08:57 INFO - Using real prefix '/tools/python27' 03:08:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:08:58 INFO - Installing distribute.............................................................................................................................................................................................done. 03:09:01 INFO - Installing pip.................done. 03:09:01 INFO - Return code: 0 03:09:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:01 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:01 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 03:09:01 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 03:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:01 INFO - 'HOME': '/Users/cltbld', 03:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:01 INFO - 'LOGNAME': 'cltbld', 03:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:01 INFO - 'MOZ_NO_REMOTE': '1', 03:09:01 INFO - 'NO_EM_RESTART': '1', 03:09:01 INFO - 'PAGER': '/bin/cat', 03:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:01 INFO - 'PWD': '/builds/slave/test', 03:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:01 INFO - 'SHELL': '/bin/bash', 03:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:01 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:01 INFO - 'USER': 'cltbld', 03:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:01 INFO - 'XPC_FLAGS': '0x0', 03:09:01 INFO - 'XPC_SERVICE_NAME': '0', 03:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:01 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 03:09:01 INFO - Cleaning up... 03:09:01 INFO - Return code: 0 03:09:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:01 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:01 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 03:09:01 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 03:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:01 INFO - 'HOME': '/Users/cltbld', 03:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:01 INFO - 'LOGNAME': 'cltbld', 03:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:01 INFO - 'MOZ_NO_REMOTE': '1', 03:09:01 INFO - 'NO_EM_RESTART': '1', 03:09:01 INFO - 'PAGER': '/bin/cat', 03:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:01 INFO - 'PWD': '/builds/slave/test', 03:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:01 INFO - 'SHELL': '/bin/bash', 03:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:01 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:01 INFO - 'USER': 'cltbld', 03:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:01 INFO - 'XPC_FLAGS': '0x0', 03:09:01 INFO - 'XPC_SERVICE_NAME': '0', 03:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:02 INFO - Downloading/unpacking psutil>=0.7.1 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:09:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:04 INFO - Installing collected packages: psutil 03:09:04 INFO - Running setup.py install for psutil 03:09:04 INFO - building 'psutil._psutil_osx' extension 03:09:04 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 03:09:05 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 03:09:05 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 03:09:05 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 03:09:05 INFO - building 'psutil._psutil_posix' extension 03:09:05 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 03:09:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:09:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:09:05 INFO - ^ 03:09:05 INFO - 1 warning generated. 03:09:05 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 03:09:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:05 INFO - Successfully installed psutil 03:09:05 INFO - Cleaning up... 03:09:05 INFO - Return code: 0 03:09:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:05 INFO - 'HOME': '/Users/cltbld', 03:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:05 INFO - 'LOGNAME': 'cltbld', 03:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:05 INFO - 'MOZ_NO_REMOTE': '1', 03:09:05 INFO - 'NO_EM_RESTART': '1', 03:09:05 INFO - 'PAGER': '/bin/cat', 03:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:05 INFO - 'PWD': '/builds/slave/test', 03:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:05 INFO - 'SHELL': '/bin/bash', 03:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:05 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:05 INFO - 'USER': 'cltbld', 03:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:05 INFO - 'XPC_FLAGS': '0x0', 03:09:05 INFO - 'XPC_SERVICE_NAME': '0', 03:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:09:08 INFO - Installing collected packages: mozsystemmonitor 03:09:08 INFO - Running setup.py install for mozsystemmonitor 03:09:08 INFO - Successfully installed mozsystemmonitor 03:09:08 INFO - Cleaning up... 03:09:08 INFO - Return code: 0 03:09:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:08 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:08 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 03:09:08 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 03:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:08 INFO - 'HOME': '/Users/cltbld', 03:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:08 INFO - 'LOGNAME': 'cltbld', 03:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:08 INFO - 'MOZ_NO_REMOTE': '1', 03:09:08 INFO - 'NO_EM_RESTART': '1', 03:09:08 INFO - 'PAGER': '/bin/cat', 03:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:08 INFO - 'PWD': '/builds/slave/test', 03:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:08 INFO - 'SHELL': '/bin/bash', 03:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:08 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:08 INFO - 'USER': 'cltbld', 03:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:08 INFO - 'XPC_FLAGS': '0x0', 03:09:08 INFO - 'XPC_SERVICE_NAME': '0', 03:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:09 INFO - Downloading/unpacking blobuploader==1.2.4 03:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:11 INFO - Downloading blobuploader-1.2.4.tar.gz 03:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:09:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:09:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:12 INFO - Downloading docopt-0.6.1.tar.gz 03:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:09:12 INFO - Installing collected packages: blobuploader, requests, docopt 03:09:12 INFO - Running setup.py install for blobuploader 03:09:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:09:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:09:13 INFO - Running setup.py install for requests 03:09:13 INFO - Running setup.py install for docopt 03:09:13 INFO - Successfully installed blobuploader requests docopt 03:09:13 INFO - Cleaning up... 03:09:13 INFO - Return code: 0 03:09:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:13 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:13 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 03:09:13 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 03:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:13 INFO - 'HOME': '/Users/cltbld', 03:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:13 INFO - 'LOGNAME': 'cltbld', 03:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:13 INFO - 'MOZ_NO_REMOTE': '1', 03:09:13 INFO - 'NO_EM_RESTART': '1', 03:09:13 INFO - 'PAGER': '/bin/cat', 03:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:13 INFO - 'PWD': '/builds/slave/test', 03:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:13 INFO - 'SHELL': '/bin/bash', 03:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:13 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:13 INFO - 'USER': 'cltbld', 03:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:13 INFO - 'XPC_FLAGS': '0x0', 03:09:13 INFO - 'XPC_SERVICE_NAME': '0', 03:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-1CBpLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-sN1XOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-MUBGcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-USznEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-0aZhIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-1QbP9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-d46juL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-vTAOug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-nHczfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:14 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-rFjdF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:15 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-sxKQLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:15 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-jlKc29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:15 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-dtH7qI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:15 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-x2Y7YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:15 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-gtfxVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:15 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-Dxfa4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:15 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-iPPR0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:09:15 INFO - Running setup.py install for manifestparser 03:09:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:09:15 INFO - Running setup.py install for mozcrash 03:09:16 INFO - Running setup.py install for mozdebug 03:09:16 INFO - Running setup.py install for mozdevice 03:09:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Running setup.py install for mozfile 03:09:16 INFO - Running setup.py install for mozhttpd 03:09:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Running setup.py install for mozinfo 03:09:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Running setup.py install for mozInstall 03:09:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:09:16 INFO - Running setup.py install for mozleak 03:09:17 INFO - Running setup.py install for mozlog 03:09:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:09:17 INFO - Running setup.py install for moznetwork 03:09:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:09:17 INFO - Running setup.py install for mozprocess 03:09:17 INFO - Running setup.py install for mozprofile 03:09:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:09:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:09:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:09:17 INFO - Running setup.py install for mozrunner 03:09:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:09:17 INFO - Running setup.py install for mozscreenshot 03:09:18 INFO - Running setup.py install for moztest 03:09:18 INFO - Running setup.py install for mozversion 03:09:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:09:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:09:18 INFO - Cleaning up... 03:09:18 INFO - Return code: 0 03:09:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:18 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:18 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 03:09:18 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 03:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:18 INFO - 'HOME': '/Users/cltbld', 03:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:18 INFO - 'LOGNAME': 'cltbld', 03:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:18 INFO - 'MOZ_NO_REMOTE': '1', 03:09:18 INFO - 'NO_EM_RESTART': '1', 03:09:18 INFO - 'PAGER': '/bin/cat', 03:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:18 INFO - 'PWD': '/builds/slave/test', 03:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:18 INFO - 'SHELL': '/bin/bash', 03:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:18 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:18 INFO - 'USER': 'cltbld', 03:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:18 INFO - 'XPC_FLAGS': '0x0', 03:09:18 INFO - 'XPC_SERVICE_NAME': '0', 03:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-eI5Uzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:18 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)) 03:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-yFQ7Ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:18 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)) 03:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-3UQZ1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:18 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)) 03:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-6VrGuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-XRqgJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-eaiIpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-RYgsD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-DdLhb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-Djtd0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-2NT32c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-dkpqid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-gaH6Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:19 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)) 03:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:19 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-pNLBkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:20 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)) 03:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:20 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-1cWJJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:20 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)) 03:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:20 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-qAwlHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:20 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)) 03:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:20 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-QgPHFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:20 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)) 03:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:20 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-lfWaPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:20 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)) 03:09:20 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)) 03:09:20 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)) 03:09:20 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)) 03:09:20 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)) 03:09:20 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)) 03:09:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:22 INFO - Downloading blessings-1.6.tar.gz 03:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:09:23 INFO - Installing collected packages: blessings 03:09:23 INFO - Running setup.py install for blessings 03:09:23 INFO - Successfully installed blessings 03:09:23 INFO - Cleaning up... 03:09:23 INFO - Return code: 0 03:09:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:23 INFO - Reading from file tmpfile_stdout 03:09:23 INFO - Current package versions: 03:09:23 INFO - blessings == 1.6 03:09:23 INFO - blobuploader == 1.2.4 03:09:23 INFO - docopt == 0.6.1 03:09:23 INFO - manifestparser == 1.1 03:09:23 INFO - mozInstall == 1.12 03:09:23 INFO - mozcrash == 0.17 03:09:23 INFO - mozdebug == 0.1 03:09:23 INFO - mozdevice == 0.48 03:09:23 INFO - mozfile == 1.2 03:09:23 INFO - mozhttpd == 0.7 03:09:23 INFO - mozinfo == 0.9 03:09:23 INFO - mozleak == 0.1 03:09:23 INFO - mozlog == 3.1 03:09:23 INFO - moznetwork == 0.27 03:09:23 INFO - mozprocess == 0.22 03:09:23 INFO - mozprofile == 0.28 03:09:23 INFO - mozrunner == 6.11 03:09:23 INFO - mozscreenshot == 0.1 03:09:23 INFO - mozsystemmonitor == 0.0 03:09:23 INFO - moztest == 0.7 03:09:23 INFO - mozversion == 1.4 03:09:23 INFO - psutil == 3.1.1 03:09:23 INFO - requests == 1.2.3 03:09:23 INFO - wsgiref == 0.1.2 03:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:23 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')]} 03:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:23 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')]} 03:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:23 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:23 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 03:09:23 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 03:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:23 INFO - 'HOME': '/Users/cltbld', 03:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:23 INFO - 'LOGNAME': 'cltbld', 03:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:23 INFO - 'MOZ_NO_REMOTE': '1', 03:09:23 INFO - 'NO_EM_RESTART': '1', 03:09:23 INFO - 'PAGER': '/bin/cat', 03:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:23 INFO - 'PWD': '/builds/slave/test', 03:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:23 INFO - 'SHELL': '/bin/bash', 03:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:23 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:23 INFO - 'USER': 'cltbld', 03:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:23 INFO - 'XPC_FLAGS': '0x0', 03:09:23 INFO - 'XPC_SERVICE_NAME': '0', 03:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 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)) 03:09:23 INFO - Cleaning up... 03:09:23 INFO - Return code: 0 03:09:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:09:23 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')]} 03:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:23 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')]} 03:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:23 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:23 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 03:09:23 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 03:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:23 INFO - 'HOME': '/Users/cltbld', 03:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:23 INFO - 'LOGNAME': 'cltbld', 03:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:23 INFO - 'MOZ_NO_REMOTE': '1', 03:09:23 INFO - 'NO_EM_RESTART': '1', 03:09:23 INFO - 'PAGER': '/bin/cat', 03:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:23 INFO - 'PWD': '/builds/slave/test', 03:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:23 INFO - 'SHELL': '/bin/bash', 03:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:23 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:23 INFO - 'USER': 'cltbld', 03:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:23 INFO - 'XPC_FLAGS': '0x0', 03:09:23 INFO - 'XPC_SERVICE_NAME': '0', 03:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:24 INFO - Downloading/unpacking jsonschema 03:09:24 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 03:09:24 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 03:09:24 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 03:09:24 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 03:09:24 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 03:09:24 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 03:09:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:09:26 INFO - Downloading/unpacking functools32 (from jsonschema) 03:09:26 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 03:09:26 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 03:09:26 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 03:09:26 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 03:09:26 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 03:09:26 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 03:09:26 INFO - Downloading functools32-3.2.3-2.tar.gz 03:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:09:27 INFO - warning: no files found matching '*.txt' 03:09:27 INFO - no previously-included directories found matching 'build' 03:09:27 INFO - no previously-included directories found matching 'dist' 03:09:27 INFO - no previously-included directories found matching '.git*' 03:09:27 INFO - Installing collected packages: jsonschema, functools32 03:09:27 INFO - Running setup.py install for functools32 03:09:27 INFO - warning: no files found matching '*.txt' 03:09:27 INFO - no previously-included directories found matching 'build' 03:09:27 INFO - no previously-included directories found matching 'dist' 03:09:27 INFO - no previously-included directories found matching '.git*' 03:09:27 INFO - Successfully installed jsonschema functools32 03:09:27 INFO - Cleaning up... 03:09:27 INFO - Return code: 0 03:09:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:27 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 0x105070a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105080420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95285ab7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, '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.waKKfxvPNc/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.e8UmImICZf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 03:09:27 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 03:09:27 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 03:09:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:09:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:27 INFO - 'HOME': '/Users/cltbld', 03:09:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:27 INFO - 'LOGNAME': 'cltbld', 03:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:27 INFO - 'MOZ_NO_REMOTE': '1', 03:09:27 INFO - 'NO_EM_RESTART': '1', 03:09:27 INFO - 'PAGER': '/bin/cat', 03:09:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:27 INFO - 'PWD': '/builds/slave/test', 03:09:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:27 INFO - 'SHELL': '/bin/bash', 03:09:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:09:27 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:09:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:27 INFO - 'USER': 'cltbld', 03:09:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:27 INFO - 'XPC_FLAGS': '0x0', 03:09:27 INFO - 'XPC_SERVICE_NAME': '0', 03:09:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:27 INFO - Downloading/unpacking flake8 03:09:27 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 03:09:27 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 03:09:27 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 03:09:27 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 03:09:27 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 03:09:27 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 03:09:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:09:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:09:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:09:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:09:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:09:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:09:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:09:31 INFO - no previously-included directories found matching 'docs/_build' 03:09:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:09:31 INFO - Running setup.py install for pep8 03:09:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:09:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:09:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:09:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:09:31 INFO - no previously-included directories found matching 'docs/_build' 03:09:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:09:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:09:31 INFO - Cleaning up... 03:09:31 INFO - Return code: 0 03:09:31 INFO - Running post-action listener: _resource_record_post_action 03:09:31 INFO - Running post-action listener: _start_resource_monitoring 03:09:31 INFO - Starting resource monitoring. 03:09:31 INFO - ##### 03:09:31 INFO - ##### Running install step. 03:09:31 INFO - ##### 03:09:31 INFO - Running pre-action listener: _resource_record_pre_action 03:09:31 INFO - Running main action method: install 03:09:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:32 INFO - Reading from file tmpfile_stdout 03:09:32 INFO - Detecting whether we're running mozinstall >=1.0... 03:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:09:32 INFO - Reading from file tmpfile_stdout 03:09:32 INFO - Output received: 03:09:32 INFO - Usage: mozinstall [options] installer 03:09:32 INFO - Options: 03:09:32 INFO - -h, --help show this help message and exit 03:09:32 INFO - -d DEST, --destination=DEST 03:09:32 INFO - Directory to install application into. [default: 03:09:32 INFO - "/builds/slave/test"] 03:09:32 INFO - --app=APP Application being installed. [default: firefox] 03:09:32 INFO - mkdir: /builds/slave/test/build/application 03:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:10:03 INFO - Reading from file tmpfile_stdout 03:10:03 INFO - Output received: 03:10:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:10:03 INFO - Running post-action listener: _resource_record_post_action 03:10:03 INFO - ##### 03:10:03 INFO - ##### Running run-tests step. 03:10:03 INFO - ##### 03:10:03 INFO - Running pre-action listener: _resource_record_pre_action 03:10:03 INFO - Running main action method: run_tests 03:10:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:10:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:10:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:10:03 INFO - 2016-05-28 03:10:03.810 screenresolution[1657:10223] starting screenresolution argv=screenresolution get 03:10:03 INFO - 2016-05-28 03:10:03.813 screenresolution[1657:10223] Display 0: 1600x1200x32@60 03:10:03 INFO - 2016-05-28 03:10:03.819 screenresolution[1658:10226] starting screenresolution argv=screenresolution list 03:10:03 INFO - Available Modes on Display 0 03:10:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:10:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:10:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:10:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:10:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:10:04 INFO - Intel Iris: 03:10:04 INFO - Chipset Model: Intel Iris 03:10:04 INFO - Type: GPU 03:10:04 INFO - Bus: Built-In 03:10:04 INFO - VRAM (Dynamic, Max): 1536 MB 03:10:04 INFO - Vendor: Intel (0x8086) 03:10:04 INFO - Device ID: 0x0a2e 03:10:04 INFO - Revision ID: 0x0009 03:10:04 INFO - Displays: 03:10:04 INFO - Display: 03:10:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:10:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:10:04 INFO - Display Serial Number: 1600x1200 60 03:10:04 INFO - Main Display: Yes 03:10:04 INFO - Mirror: Off 03:10:04 INFO - Online: Yes 03:10:04 INFO - Rotation: Supported 03:10:04 INFO - Return code: 0 03:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:10:04 INFO - Python 2.7.3 03:10:04 INFO - Return code: 0 03:10:04 INFO - grabbing minidump binary from tooltool 03:10: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')]} 03:10:04 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f0fe00>, '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 0x104d78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f0ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:10:04 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 03:10:04 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 03:10:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:10:04 INFO - Return code: 0 03:10:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:10:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:10:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:10:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:10:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:10:05 INFO - Return code: 0 03:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0224 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.waKKfxvPNc/Render', 03:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:05 INFO - 'HOME': '/Users/cltbld', 03:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:05 INFO - 'LOGNAME': 'cltbld', 03:10:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:10:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:05 INFO - 'MOZ_NO_REMOTE': '1', 03:10:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:10:05 INFO - 'NO_EM_RESTART': '1', 03:10:05 INFO - 'PAGER': '/bin/cat', 03:10:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:05 INFO - 'PWD': '/builds/slave/test', 03:10:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:05 INFO - 'SHELL': '/bin/bash', 03:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e8UmImICZf/Listeners', 03:10:05 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 03:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:05 INFO - 'USER': 'cltbld', 03:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:05 INFO - 'XPC_FLAGS': '0x0', 03:10:05 INFO - 'XPC_SERVICE_NAME': '0', 03:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:10:05 INFO - mozversion application_buildid: 20160525152143 03:10:05 INFO - mozversion application_changeset: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 03:10:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:10:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:10:05 INFO - mozversion application_name: Firefox 03:10:05 INFO - mozversion application_remotingname: firefox-dev 03:10:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:10:05 INFO - mozversion application_vendor: Mozilla 03:10:05 INFO - mozversion application_version: 48.0a2 03:10:05 INFO - mozversion platform_buildid: 20160525152143 03:10:05 INFO - mozversion platform_changeset: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 03:10:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:10:05 INFO - mozversion platform_version: 48.0a2 03:10:05 INFO - using testdate: 1464430205 03:10:05 INFO - actual date: 1464430205 03:10:05 INFO - starting webserver on 'localhost:49278' 03:10:05 INFO - SUITE-START | Running 2 tests 03:10:05 INFO - TEST-START | dromaeo_css 03:10:05 INFO - operating with platform_type : mac_ 03:10:05 INFO - Initialising browser for dromaeo_css test... 03:10:05 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpCIYBW3/profile http://localhost:49278/getInfo.html) 03:10:08 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 03:10:08 INFO - PROCESS | 1676 | colorDepth:24 03:10:08 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 03:10:08 INFO - PROCESS | 1676 | __metrics 03:10:08 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 03:10:09 INFO - TEST-INFO | 1676: exit 0 03:10:09 INFO - Browser initialized. 03:10:09 INFO - Running cycle 1/1 for dromaeo_css test... 03:10:09 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpCIYBW3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:20 INFO - PROCESS | 1680 | RSS: Main: 221253632 03:10:20 INFO - PROCESS | 1680 | 03:11:10 INFO - PROCESS | 1680 | 1464430270639 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] 03:13:22 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 03:13:23 INFO - PROCESS | 1680 | RSS: Main: 231714816 03:13:23 INFO - PROCESS | 1680 | 03:16:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 03:16:26 INFO - PROCESS | 1680 | RSS: Main: 243564544 03:16:26 INFO - PROCESS | 1680 | 03:19:27 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 03:19:28 INFO - PROCESS | 1680 | RSS: Main: 245313536 03:19:28 INFO - PROCESS | 1680 | 03:22:30 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 03:22:30 INFO - PROCESS | 1680 | RSS: Main: 247234560 03:22:30 INFO - PROCESS | 1680 | 03:25:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 03:25:34 INFO - PROCESS | 1680 | RSS: Main: 250064896 03:25:34 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 03:28:36 INFO - PROCESS | 1680 | RSS: Main: 250646528 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | __start_tp_report 03:28:36 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 03:28:36 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 03:28:36 INFO - PROCESS | 1680 | |i|pagename|runs| 03:28:36 INFO - PROCESS | 1680 | |0;dojo.html;3504.579450465944;3543.2204914918725;3580.677739003492;3601.7298702597304;3616.168281295302;5738.713064346776;5771.047777116771;5775.740091695873;5813.098969659699;5828.0966450200285;5703.629264097831;5807.709614519278;5820.767169313233;5845.678487683176;5859.296884373871;4299.871003869889;5352.384475785296;5355.732213389334;5370.838874833761;5384.3807962084275;5252.684838909678;5337.2527846101575;5383.596230282725;5437.238786569864;5458.972705136469;5345.518903298703;5391.919121213185;5398;5408.4591540845995;5431.402545719968;5368.0605893968595;5391.676499410048;5401.999999999961;5405.135178371481;5454.727263636842;4274.5939135782055;5124.92312615311;5303.416624171338;5310.893782124374;5325.920111198335;5580.804671836507;5637;5659.151127330892;5661.122526008476;5710.685912274823;6260.937390626081;6272.56092073555;6342.746290148389;6345.49236061114;6346.936530634681;6215.7513699451965;6255.186825712673;6261.311255761862;6261.749530018794;6290.842728931768;4777.235642297285;5874.794382196603;5900.203472531153;5908.231929849135;5912.231409916726;6208.00671892495;6346.2067241594805;6364.522660800459;6395.616263024233;6401.775937842153;5193.454687257859;5297.629165958423;6134.7121296814075;6161.938380616226;6271.498280137578;5703.916255911364;5934.19888315072;6187.16473276102;6321.1150438938585;6333.619982801047;5232.2674825524455;6138.600990935575;6261.749530018748;6287.842803929939;6305.905411418832;5057.69653820772;5346.278252435949;5371.248025276464;6188.81433556994;6299.0866324382705;4988.326575912205;4989.90020199594;5004.274380214849;5030.748462576857;5039.647224694236;4414.55854414556;4437.64498840092;4479.641628669699;4496.100779844044;4520.095980803852;5727.398623144593;5788.623739456922;5809.854753631109;5828.708564571754;5870.823875283749;6291.622502649856;6294.402031806971;6299.307076221612;6311.652859092794;6313.684315784192;5636.323641163087;5793.478586927113;5826.76692932278;5830.300363956268;5849.151872978395;5374.516293533601;5475.123980163155;5478.233047373371;5479.506844384025;5505.064139096283;6376.936230637727;6469.644169570719;6569.146011018633;6574.4411725002;6582.703778330082;6253.624782513064;6283.465905398092;6314.494840412664;6330.208723909512;6342.175517182921;5860.2967643883;6239.970404883144;6507.934920650733;6538.542302038812;6555.606663600009;6233.532485063729;6245.188125543832;6261.029540421242;6264.843378915563;6264.968675156595;6279.340669229665;6290.21372328459;6292.182016337847;6302.180716507009;6309.148264984168;5102.821401250939;5800.868830577997;6023.969880150571;6223.284322302993;6232.25212974446;5742.310922689304;6173.6913154343;6207.689615519296;6212.81361559154;6215.502759779297;5497.725113744217;5941.702914854326;6060.848478788065;6074.453299203093;6075.969620151871;4965.528274813887;5433.347998240237;5789.363170051375;6129.350851464913;6170.6914654268;4948.727819969936;6087.543434242361;6100.664463454553;6106.816795496142;6132.41742034506 03:28:36 INFO - PROCESS | 1680 | |1;ext.html;532.3186321508471;540.4136511884604;580.0747807247438;596.4453058655452;610.9949133675104;34498.827505862464;37384.43923341152;37450.62549374506;37486.8125659372;37505.624943750554;41329.79335103328;41389.79305103478;41648.37527437083;41904;41961;9523.571439285213;9645.95177024114;9745.951270243675;9757.268204884627;9761.121499065082;9531.904680953172;9555.808883822354;9564.713058608253;9615.759606009835;9696.933337332923;9550.331476796628;9574.13832755052;9633;9677.90322096777;9679.08048735369;9613.615455381776;9676.322657413984;9693.612255509772;9706.70879873605;9721.465319407394;9559.044095590454;9702.90297097027;9710.17463515602;9724.465154416504;9737.902620973771;38331;38479.422808657895;38532.80733596314;38540.80729596362;38929.80535097334;3102.798318109338;3144.921376965571;3213.228825081987;3264.347130573895;3670.382165605085;27782;28780.424391512053;28783;28945.565816512768;28989.42021159586;2798.622186004904;2800.635917330754;2804.4110736745306;2816.1270006298114;2908.8254704717574;463638;468681;469075.6546217281;469147.6542617299;470297;35301.29397412037;36432.453513197324;60697.69651151804;61244.38755612387;61307.38692613106;35424.82287588545;35616.82191589026;35707.4643880342;35775.4633680495;35824.104397389754;15247.847521524865;15326.23368831554;15458.381664733284;15471.303791329417;15480.458183963507;12911.289879056741;12998.480060797652;13088.541901033608;13129.818316351575;13131.803022954664;8070.314023307968;8239.546824924686;8243.876341854877;8269.875951860728;8280;3454.654534546525;3470.583529976419;3484.3379757846437;3487.616362200156;3490.424080026818;949.5726922884592;955.5652178258875;1004.6483730694345;1049.5119769307157;1072.056590200634;24905.9202842855;28455.57316640252;29344;29877.253068673457;30359.392812143633;1026.4200726589454;1075.1184029096066;1164.7088227943013;1175.623800383886;1185.436917464215;15004.174770387515;15263.618409539627;15328.080315181125;15459.149746764037;15501.534953951399;630858;631410;636037;636770;640780;59225.11162332568;61119.555217791676;61231.081533777025;61469.77060458855;61494;1286.691194113406;1470.2575199524172;1492.5447738439664;1498.9956728991601;1500.0624609619206;29018.56472152877;29405.411891762473;29474.852625737592;29614.555781663723;29957.70042299549;49309.506904930495;51112.74443627758;51641;51939;54067.72966135144;418697.7195342133;491611;509140.9085909093;556384;559983;26884.924603016443;27378.863105684344;38510;38824.25290861975;43947;38333.42499862448;41322.58677413187;42889.35665965075;43256.567434325254;43629.47296844595;34731.958041258804;34925.9522214334;35482.29035419329;35815.28369432649;36240.637593623724;35723.82138089397;37002.25995480022;37031.814840925625;37584;37743.811280944516;36847.815760921025;37093.07267318231;38417.46330146763;38880;39021.80489097536;29726;31846.522302165024;32040.679593204703;32583.348333033682;33162;24695.876520617283;25009.49981000406;25038.62442063334;25338;25372.619410709223 03:28:36 INFO - PROCESS | 1680 | |2;jquery.html;360.36755494107837;400.451381607198;403.65084202165116;404.6964776417687;413.64013308421676;15531.68936621267;15576.766348504752;20262.290819821308;20463.48841278967;20585.764854108744;16224.594385140377;19767.616266861325;20184.192632294726;20211.696824547642;20915.790842091574;5704.401037891024;5828.970855145729;5833.8833223335505;5989.760409583611;6076.149339092531;5874.324452687932;5914.88170236595;5982.491488223506;5996.100584912255;5997.250343707036;5672.404397538261;5921.289445266552;5938.079597662369;6000.75996960121;6001.849953751147;5965.821025369246;5983.940160598404;6010.549208809357;6012.9398706013035;6033.245844269466;5093.643444958854;5223.294855194538;5254.842354729364;5269.130593452076;5296.152615581488;25761.484770304676;25788.871055644602;26127;26349.3412664683;26861.86569067142;2443.169322430382;2585.2115104893;2625.1599488163797;2703.3917368592106;2733.1117386849155;12948.611541653765;14420.423183072664;14653.70692586153;14671.926640366837;14697.853021469862;1560.9531714048596;1659.821526716033;1715.7254839225664;1780.4213630569986;1825.2237011975265;101771.49196336637;105105;106460;106941.39587906188;110600;8906.554672266491;11050.060744836617;11462.828057579143;11476.483558239732;11772.882271177179;11857.407129643483;12845.165064270792;13646;14179.929100354639;14228.14631122136;7605.82109772975;8492.490450572985;8919.464832110094;8931.910680893237;9029.458232506071;9154.771130721652;9178.54107294646;9189.989101198862;9244.90755092454;9269.44383337002;6789.558678685869;6856.485763567753;6878;6878.518503704793;6910.412614927787;1642.6386195037073;1934.429343343717;1961.8920959347372;1963.7152612871055;2011.7083022961588;760.9916860160199;887.4941283468361;979.911807937289;1038.8753349598096;1045.1899777672365;20233.392998210078;21001.264955726478;21279.574408512053;21304.36086917395;21354.78645213559;1010.8736407949007;1037.7198156497702;1043.4991204221865;1066.6746642274181;1079.282277285612;11272.66182014541;11624.651260462031;11625.12811539121;11656;11675.76648467026;561086;574866;592691;593798;602987;109781;110394;111181.44409277901;113563.43218283856;116783;915.4736026784625;1046.6232156423682;1059.4808543813476;1102.4267380961855;1106.2975010868156;18901;19001.049947502845;19173.80826191776;19174.61650767005;19182.90408548004;31094.378112438077;47642;48823.267650984555;49724;51687;69443.61112777615;69887.65056174686;70945.22636934118;73060.26939730748;73618.26381736329;8149.7147599833725;8177.959110204649;8293.543855088028;8423.4945903246;8524.531150786766;11958.103142264192;12816.295103769382;12935.900448461985;12974.481020759442;12986.220826750046;10261.74345641365;10661.307015043998;10982.725431864268;11010.889891101308;11527.135464840338;10850;10865.782684346426;10952.616658416917;11306.49120789583;11474.827877581676;11312.20814543007;11334.093272538343;11567.826482602599;11637.243579167327;11934.104942129201;8372.246497815226;8377.329813615019;8539.74380768578;8595.925509311335;8778.648854045767;10467.214958877963;11468.770624587294;11627.953484186464;11681.649550513499;11702.824457632973 03:28:36 INFO - PROCESS | 1680 | |3;mootools.html;1588.499622618875;1610.3075677458692;1867;1918.9616207675856;2000.7999200079985;7593.658285377158;7948.324392426651;8564.186402291787;8775.166359195882;8958.238549723274;8741.213290803826;8742.562871856413;8789.077146899563;8853.380263381567;8892.64429422824;2343.3438637181575;2352.306069709431;2366.7869891709743;2382.3924899150693;2387.988060059697;2446.889889954947;2456.459578892641;2471.2462698876834;2486.2292689266296;2487.340854673513;2453.325335532734;2471.258622413278;2475.4677744284977;2479.7396273391305;2481.4292712676097;2210.2706106984656;2440.6461063145834;2442.7068751749725;2465.1248806673643;2475.6905386826556;2422.8425152365216;2433.634954756783;2447.730749617541;2458.360826185187;2473.591857343536;7676.577788221702;8832.425892317044;8958.23854972328;8967.789348437942;9007.909920900773;4620.584147426748;5292.047431462335;5406.432324605938;5440;5466.316710411199;3503.1242189452637;3549.378858699743;3577.8926632201074;3580.2839432113683;3612.3858943979585;899.1907283444951;1041.8906014868405;1053.9104176145038;1054.2356791326304;1061.8035663402295;10913;11166.720831979184;11417.48621312043;11615.825762613567;11729;4669.789859456333;4791;4798.328234047236;4805.831795887128;4820.68665536739;10004.299699021;10073;10102.444365559964;10110.595576177018;10111.7472063199;5300.363956325188;5319.255304257407;5350.277712508761;5361.08861493547;5370;3847.8653247136217;4406.933895991563;4869.70781753096;4915.238138088602;4919.040787046563;4047.4535937648043;4065.308897487434;4149.398337241083;4175.352820312856;4176.081262122329;1359.3746876436753;1379.648189711637;1380.565121986571;1380.675541247806;1399.7900314952838;792.6116203060574;831.5177197225595;875.2757068574897;934.5887809363858;955.9044095590385;5664.461876121762;5818.330891947396;5907.042590524757;6075.997460418981;6078.969605151946;944.3153713557616;965.0446058402131;966.8259713251682;997.2271489595621;1014.4927536231884;3953.3081710700512;4041.151358214749;4058.512978442606;4091.9181616367505;4093.7748423836974;10505.842412363821;10548.208884333551;10657.360558366523;10781.460926953621;10844.240903136862;10429.843552346569;10448.634297799541;10591.311564748266;10712.678619641423;10784.946075269574;8293.751187464386;8348.707795227137;8353.624086916103;8445.282151017233;8449.028361738356;5901.704914754331;5956.463918247378;5989.910151347646;6044.697765111815;6088.056351265561;9302.95348523253;9399.248060155034;9418;9443.575039123394;9478.38390504615;9399.577019034297;9635.518224088628;9820.65627703027;9826.361286516354;9940.900590994286;6812.727490900506;7086.149662040382;7211.350978411968;7439.9256007439235;7552.320291173821;7779.883301750591;7817.335526480086;7868.25251601097;7869.370450364072;7930.444868859125;6957.339052789976;7047.436205103477;7065.57606543609;7141.178764442156;7181.748638797617;6932.168139823253;6946.340097690711;6986.371226589631;7013.123359580052;7047.823804404726;6661.4004739573675;6716.597004179765;6741.932580674326;6754.763583274562;6839.008343790222;4781.067691800065;4812.230043193073;4822.13201623697;4847.854564363075;4862.562369386631;4928.901421971469;4938.950610493849;4964.801407943642;4997.100521906092;5116.9744151279 03:28:36 INFO - PROCESS | 1680 | |4;prototype.html;294.94543509450756;315.9241781972326;328.94079065768153;330.12187581034453;332.330354336013;6429.292777794439;6518.924377477711;6588.308227636101;6626.834329141774;6682.331766823309;5261.842144735654;6571.605703657784;6577.473802095833;6624.072629831815;6628.70170842312;4881.170201065827;4933.753312334369;4936.234883593049;4939.555440010389;4940.1601727706375;4930.4083509978855;4934.1611925972675;4934.185859333205;4956.529129732669;4969.726665033421;4495.6628252881;4583.968607063401;4826.34844296019;4867.634927380443;4893.388326459192;4599.195140850338;4817.349657796205;4867.3915760529935;4897.975510122462;4902.28916807061;3378.814165220912;4059.776712280823;4066.8576599818866;4066.9186616267807;4081.9183616327805;6289;6311.179546658951;6317;6329.3354197809485;6345.904811427832;5052.747362631853;5169.095408303532;5631.549476041907;5659.434056594308;5696.6866822324755;7095.396891264249;7095.538789978635;7118.857622847514;7134.6789394476855;7137.964995075685;4557.817687292504;4558.794854231567;4559.430071241095;4563.566461186182;4581;5704.600677952545;7469.701211951516;7476.1776204617445;7531.548107113591;7586.658600362997;4516.1419330327135;4611.262198048296;4611.423572053493;4619;4648.697834640738;4697.647676424282;4701.506341834058;4706.458757242893;4712.2224832902875;4725.810967561259;5560.165975103686;5569.387367389584;5572.86067848307;5582.692951887685;5600.719964001783;6847.383735463733;7112.786616401516;7127.607981561064;7136.536125151849;7146.03528523653;1607.4856046065381;1614.3058484851392;1625.2848746551479;1627.6337823989509;1630.6331075507917;1025.1132770153913;1034.622362837574;1045.7856139491387;1046.853440518328;1048.1614708233383;6465.321141280192;6530.967345163243;6604.900926486107;6633.104530888365;6691.899621505681;7533.773986780406;7545.396368290523;7551.358134558514;7578.848423031509;7595.430342724318;5824.94175058244;6683.064370988066;6722.66386680664;6725.293844146392;6743.797686069328;3744.5693745219105;3993.1614360983936;3994.6604538614542;3996.6403023728003;3998.260321840469;7638.885416718754;8010.799730006796;8013.919860801318;8034.116247212802;8044.517328960164;7717.922820771832;7787.415943804124;7793.103793063757;7804.336631386395;7820.335271501872;6967.895481567781;7954.801129971681;7976.441649084624;7987.68049278034;8027.5584842834205;7255.419566434778;7520.548767073993;7544.962275188589;7550.622468876424;7575.545467271982;5876.823695289148;7421.777346679608;7439;7450.8509829802015;7457.179710231979;7959.124496305286;7972.561509117054;7984.041914970241;8000.199980002186;8026.237507436784;4743.051389722001;4769.260764581495;4817.542333478314;4855.36880205571;4859.732714700725;7074.929250707427;7301.525400848928;7304.890426643711;7330.633468326669;7375.520591161558;7066.470014748812;7066.9752885832295;7074.363307302162;7085.114360704912;7119.430445564239;7047.260037696173;7051.682674279567;7066.364027237573;7081.362677359062;7090.680919358538;6906.309369063053;6908.792736217922;6911.17065952089;6918.308169183243;6923.515353925177;6087.908681369871;6972.965135174291;7085.50401471892;7162.856742865009;7189.676464559003;6777.966110169615;6950.131233595801;7034.683439245144;7038.648067596702;7153.85692286141 03:28:36 INFO - PROCESS | 1680 | |5;yui.html;647.2976820150137;692.2281655953611;737.3400806278379;739.7115125101211;769.2154002917025;14847.183404912725;14893;15108.017978831382;15114.622134446645;15117.168555729431;13491.797623035638;13705.246211458365;13887.513937012207;13934.233617151052;13942.442302307896;753.3295367123264;906.4923642760042;915.9786837675977;926.7219834049791;926.7683079230193;903.0969030969031;916;938.5776400619715;944.0417975754615;946.3375637054089;986.0632399220733;987.476637382185;991.3704797453632;991.920646348294;994.3735446668625;939.9436033837992;945.4941606240628;960.6589660670467;979.5543027922279;982.7150126463317;696.6551556979277;737.6827963975488;738.3354980517523;740.9555426674362;741.1661880384568;14737.263136843116;14787.186704731343;14982;15016.624584385478;15036.398544058224;10642.361458312449;12618.621441356774;12628.242305461701;12811.87188128116;12889.419976101004;10009.949950250202;10048.145907597864;10098.394096354243;10116.54475548602;10273.178145748323;3032.272254658888;3246.788958717699;3317.834108294576;3470.889315419067;3499.7025252853537;75358.62320688416;80772;81060.18939810585;83846;84464.57767711184;9393.953030234805;9420.95289523548;9458;9487.241020718326;9555.23558115349;21107.472313191985;21718;21840.672389914165;21895.890520547615;21897.890510547662;7224.891626625605;7303.233160518069;7304.744333948286;7308.890366644505;7358.11702595692;3933.0757272041046;3969.106950936074;3984.820683069269;4055.5741647127215;4077.857274995361;3410.4884267360094;3424.4520876659617;3467.531883195736;3480;3481.5299934509026;622.7540121651922;623.9594426362272;627.7363507326934;629.1632129268063;633.1926793338438;564.3199944067389;592.3780030967467;596.8955432799243;597.8415719834247;603.514171092275;6991.265917078736;7994.760157195294;8152.206514566786;8226;8446.322067594434;572.4561666416837;581.1428143488321;597.1997523318773;598.5002522893345;607.1105829959131;3778.1499162688174;3822.7897465639658;3870.5161854768153;3876.341022026262;4068.6541643960004;93714;96178.51910740402;96311.51844240873;96339.03660963301;98317;80613;81480.59259703664;82323.5883820577;82438.58780706178;82544.17455825485;64259.67870160619;65223.695526089214;65607.67196163989;66079.66960165264;66230.33769662338;7624.5425274483705;7784.494007889695;8044.235797599219;8144.674213031412;8201.343892488705;47753.761231193625;47792.522074778804;48516.75741621269;48855.0457981691;49780.25329619986;61499.385006151155;61573.76852462836;63328.73342533215;63880.36119638744;64104.35895640984;10810.405427701551;10897.128229741444;10934.56261749521;10998.505067271832;11370.033547148254;11907.702307442036;12024.75950481003;12046.156769026084;12333.814992775295;12446.190997585129;11228;11375.146863985068;11561.959423651911;11794.941025294818;11962.342071186169;10922.50848711794;11273.492692828679;11378.601748938747;11415.200935934405;12124.515019399125;11073.944630276797;11173;11247.606333778278;11303.717407064889;12020.158588898694;9353.953230234078;9466.52667366615;9560.426374417557;9746.85379719319;9772.9022709772;8541.871871921801;8947.328950328621;9408.059194080537;9510.95244523773;9837.852432213665 03:28:36 INFO - PROCESS | 1680 | __end_tp_report 03:28:36 INFO - PROCESS | 1680 | __start_cc_report 03:28:36 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,418 03:28:36 INFO - PROCESS | 1680 | __end_cc_report 03:28:36 INFO - PROCESS | 1680 | __startTimestamp1464431316689__endTimestamp 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | ------- Summary: start ------- 03:28:36 INFO - PROCESS | 1680 | Number of tests: 6 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | [#0] dojo.html Cycles:165 Average:5740.47 Median:5845.68 stddev:646.82 (11.1%) stddev-sans-first:624.56 03:28:36 INFO - PROCESS | 1680 | Values: 3504.6 3543.2 3580.7 3601.7 3616.2 5738.7 5771.0 5775.7 5813.1 5828.1 5703.6 5807.7 5820.8 5845.7 5859.3 4299.9 5352.4 5355.7 5370.8 5384.4 5252.7 5337.3 5383.6 5437.2 5459.0 5345.5 5391.9 5398.0 5408.5 5431.4 5368.1 5391.7 5402.0 5405.1 5454.7 4274.6 5124.9 5303.4 5310.9 5325.9 5580.8 5637.0 5659.2 5661.1 5710.7 6260.9 6272.6 6342.7 6345.5 6346.9 6215.8 6255.2 6261.3 6261.7 6290.8 4777.2 5874.8 5900.2 5908.2 5912.2 6208.0 6346.2 6364.5 6395.6 6401.8 5193.5 5297.6 6134.7 6161.9 6271.5 5703.9 5934.2 6187.2 6321.1 6333.6 5232.3 6138.6 6261.7 6287.8 6305.9 5057.7 5346.3 5371.2 6188.8 6299.1 4988.3 4989.9 5004.3 5030.7 5039.6 4414.6 4437.6 4479.6 4496.1 4520.1 5727.4 5788.6 5809.9 5828.7 5870.8 6291.6 6294.4 6299.3 6311.7 6313.7 5636.3 5793.5 5826.8 5830.3 5849.2 5374.5 5475.1 5478.2 5479.5 5505.1 6376.9 6469.6 6569.1 6574.4 6582.7 6253.6 6283.5 6314.5 6330.2 6342.2 5860.3 6240.0 6507.9 6538.5 6555.6 6233.5 6245.2 6261.0 6264.8 6265.0 6279.3 6290.2 6292.2 6302.2 6309.1 5102.8 5800.9 6024.0 6223.3 6232.3 5742.3 6173.7 6207.7 6212.8 6215.5 5497.7 5941.7 6060.8 6074.5 6076.0 4965.5 5433.3 5789.4 6129.4 6170.7 4948.7 6087.5 6100.7 6106.8 6132.4 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | [#1] ext.html Cycles:180 Average:65887.74 Median:37496.22 stddev:145096.65 (387.0%) stddev-sans-first:145420.71 03:28:36 INFO - PROCESS | 1680 | Values: 532.3 540.4 580.1 596.4 611.0 34498.8 37384.4 37450.6 37486.8 37505.6 41329.8 41389.8 41648.4 41904.0 41961.0 9523.6 9646.0 9746.0 9757.3 9761.1 9531.9 9555.8 9564.7 9615.8 9696.9 9550.3 9574.1 9633.0 9677.9 9679.1 9613.6 9676.3 9693.6 9706.7 9721.5 9559.0 9702.9 9710.2 9724.5 9737.9 38331.0 38479.4 38532.8 38540.8 38929.8 3102.8 3144.9 3213.2 3264.3 3670.4 27782.0 28780.4 28783.0 28945.6 28989.4 2798.6 2800.6 2804.4 2816.1 2908.8 463638.0 468681.0 469075.7 469147.7 470297.0 35301.3 36432.5 60697.7 61244.4 61307.4 35424.8 35616.8 35707.5 35775.5 35824.1 15247.8 15326.2 15458.4 15471.3 15480.5 12911.3 12998.5 13088.5 13129.8 13131.8 8070.3 8239.5 8243.9 8269.9 8280.0 3454.7 3470.6 3484.3 3487.6 3490.4 949.6 955.6 1004.6 1049.5 1072.1 24905.9 28455.6 29344.0 29877.3 30359.4 1026.4 1075.1 1164.7 1175.6 1185.4 15004.2 15263.6 15328.1 15459.1 15501.5 630858.0 631410.0 636037.0 636770.0 640780.0 59225.1 61119.6 61231.1 61469.8 61494.0 1286.7 1470.3 1492.5 1499.0 1500.1 29018.6 29405.4 29474.9 29614.6 29957.7 49309.5 51112.7 51641.0 51939.0 54067.7 418697.7 491611.0 509140.9 556384.0 559983.0 26884.9 27378.9 38510.0 38824.3 43947.0 38333.4 41322.6 42889.4 43256.6 43629.5 34732.0 34926.0 35482.3 35815.3 36240.6 35723.8 37002.3 37031.8 37584.0 37743.8 36847.8 37093.1 38417.5 38880.0 39021.8 29726.0 31846.5 32040.7 32583.3 33162.0 24695.9 25009.5 25038.6 25338.0 25372.6 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | [#2] jquery.html Cycles:180 Average:33693.74 Median:20958.53 stddev:97008.77 (462.9%) stddev-sans-first:97248.61 03:28:36 INFO - PROCESS | 1680 | Values: 360.4 400.5 403.7 404.7 413.6 15531.7 15576.8 20262.3 20463.5 20585.8 16224.6 19767.6 20184.2 20211.7 20915.8 5704.4 5829.0 5833.9 5989.8 6076.1 5874.3 5914.9 5982.5 5996.1 5997.3 5672.4 5921.3 5938.1 6000.8 6001.8 5965.8 5983.9 6010.5 6012.9 6033.2 5093.6 5223.3 5254.8 5269.1 5296.2 25761.5 25788.9 26127.0 26349.3 26861.9 2443.2 2585.2 2625.2 2703.4 2733.1 12948.6 14420.4 14653.7 14671.9 14697.9 1561.0 1659.8 1715.7 1780.4 1825.2 101771.5 105105.0 106460.0 106941.4 110600.0 8906.6 11050.1 11462.8 11476.5 11772.9 11857.4 12845.2 13646.0 14179.9 14228.1 7605.8 8492.5 8919.5 8931.9 9029.5 9154.8 9178.5 9190.0 9244.9 9269.4 6789.6 6856.5 6878.0 6878.5 6910.4 1642.6 1934.4 1961.9 1963.7 2011.7 761.0 887.5 979.9 1038.9 1045.2 20233.4 21001.3 21279.6 21304.4 21354.8 1010.9 1037.7 1043.5 1066.7 1079.3 11272.7 11624.7 11625.1 11656.0 11675.8 561086.0 574866.0 592691.0 593798.0 602987.0 109781.0 110394.0 111181.4 113563.4 116783.0 915.5 1046.6 1059.5 1102.4 1106.3 18901.0 19001.0 19173.8 19174.6 19182.9 31094.4 47642.0 48823.3 49724.0 51687.0 69443.6 69887.7 70945.2 73060.3 73618.3 8149.7 8178.0 8293.5 8423.5 8524.5 11958.1 12816.3 12935.9 12974.5 12986.2 10261.7 10661.3 10982.7 11010.9 11527.1 10850.0 10865.8 10952.6 11306.5 11474.8 11312.2 11334.1 11567.8 11637.2 11934.1 8372.2 8377.3 8539.7 8595.9 8778.6 10467.2 11468.8 11628.0 11681.6 11702.8 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | [#3] mootools.html Cycles:180 Average:5655.21 Median:4923.97 stddev:3098.59 (62.9%) stddev-sans-first:3092.21 03:28:36 INFO - PROCESS | 1680 | Values: 1588.5 1610.3 1867.0 1919.0 2000.8 7593.7 7948.3 8564.2 8775.2 8958.2 8741.2 8742.6 8789.1 8853.4 8892.6 2343.3 2352.3 2366.8 2382.4 2388.0 2446.9 2456.5 2471.2 2486.2 2487.3 2453.3 2471.3 2475.5 2479.7 2481.4 2210.3 2440.6 2442.7 2465.1 2475.7 2422.8 2433.6 2447.7 2458.4 2473.6 7676.6 8832.4 8958.2 8967.8 9007.9 4620.6 5292.0 5406.4 5440.0 5466.3 3503.1 3549.4 3577.9 3580.3 3612.4 899.2 1041.9 1053.9 1054.2 1061.8 10913.0 11166.7 11417.5 11615.8 11729.0 4669.8 4791.0 4798.3 4805.8 4820.7 10004.3 10073.0 10102.4 10110.6 10111.7 5300.4 5319.3 5350.3 5361.1 5370.0 3847.9 4406.9 4869.7 4915.2 4919.0 4047.5 4065.3 4149.4 4175.4 4176.1 1359.4 1379.6 1380.6 1380.7 1399.8 792.6 831.5 875.3 934.6 955.9 5664.5 5818.3 5907.0 6076.0 6079.0 944.3 965.0 966.8 997.2 1014.5 3953.3 4041.2 4058.5 4091.9 4093.8 10505.8 10548.2 10657.4 10781.5 10844.2 10429.8 10448.6 10591.3 10712.7 10784.9 8293.8 8348.7 8353.6 8445.3 8449.0 5901.7 5956.5 5989.9 6044.7 6088.1 9303.0 9399.2 9418.0 9443.6 9478.4 9399.6 9635.5 9820.7 9826.4 9940.9 6812.7 7086.1 7211.4 7439.9 7552.3 7779.9 7817.3 7868.3 7869.4 7930.4 6957.3 7047.4 7065.6 7141.2 7181.7 6932.2 6946.3 6986.4 7013.1 7047.8 6661.4 6716.6 6741.9 6754.8 6839.0 4781.1 4812.2 4822.1 4847.9 4862.6 4928.9 4939.0 4964.8 4997.1 5117.0 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | [#4] prototype.html Cycles:180 Average:5821.87 Median:6582.89 stddev:1903.72 (28.9%) stddev-sans-first:1863.32 03:28:36 INFO - PROCESS | 1680 | Values: 294.9 315.9 328.9 330.1 332.3 6429.3 6518.9 6588.3 6626.8 6682.3 5261.8 6571.6 6577.5 6624.1 6628.7 4881.2 4933.8 4936.2 4939.6 4940.2 4930.4 4934.2 4934.2 4956.5 4969.7 4495.7 4584.0 4826.3 4867.6 4893.4 4599.2 4817.3 4867.4 4898.0 4902.3 3378.8 4059.8 4066.9 4066.9 4081.9 6289.0 6311.2 6317.0 6329.3 6345.9 5052.7 5169.1 5631.5 5659.4 5696.7 7095.4 7095.5 7118.9 7134.7 7138.0 4557.8 4558.8 4559.4 4563.6 4581.0 5704.6 7469.7 7476.2 7531.5 7586.7 4516.1 4611.3 4611.4 4619.0 4648.7 4697.6 4701.5 4706.5 4712.2 4725.8 5560.2 5569.4 5572.9 5582.7 5600.7 6847.4 7112.8 7127.6 7136.5 7146.0 1607.5 1614.3 1625.3 1627.6 1630.6 1025.1 1034.6 1045.8 1046.9 1048.2 6465.3 6531.0 6604.9 6633.1 6691.9 7533.8 7545.4 7551.4 7578.8 7595.4 5824.9 6683.1 6722.7 6725.3 6743.8 3744.6 3993.2 3994.7 3996.6 3998.3 7638.9 8010.8 8013.9 8034.1 8044.5 7717.9 7787.4 7793.1 7804.3 7820.3 6967.9 7954.8 7976.4 7987.7 8027.6 7255.4 7520.5 7545.0 7550.6 7575.5 5876.8 7421.8 7439.0 7450.9 7457.2 7959.1 7972.6 7984.0 8000.2 8026.2 4743.1 4769.3 4817.5 4855.4 4859.7 7074.9 7301.5 7304.9 7330.6 7375.5 7066.5 7067.0 7074.4 7085.1 7119.4 7047.3 7051.7 7066.4 7081.4 7090.7 6906.3 6908.8 6911.2 6918.3 6923.5 6087.9 6973.0 7085.5 7162.9 7189.7 6778.0 6950.1 7034.7 7038.6 7153.9 03:28:36 INFO - PROCESS | 1680 | 03:28:36 INFO - PROCESS | 1680 | [#5] yui.html Cycles:180 Average:18183.53 Median:61536.58 stddev:25769.64 (41.9%) stddev-sans-first:25808.29 03:28:36 INFO - PROCESS | 1680 | Values: 647.3 692.2 737.3 739.7 769.2 14847.2 14893.0 15108.0 15114.6 15117.2 13491.8 13705.2 13887.5 13934.2 13942.4 753.3 906.5 916.0 926.7 926.8 903.1 916.0 938.6 944.0 946.3 986.1 987.5 991.4 991.9 994.4 939.9 945.5 960.7 979.6 982.7 696.7 737.7 738.3 741.0 741.2 14737.3 14787.2 14982.0 15016.6 15036.4 10642.4 12618.6 12628.2 12811.9 12889.4 10009.9 10048.1 10098.4 10116.5 10273.2 3032.3 3246.8 3317.8 3470.9 3499.7 75358.6 80772.0 81060.2 83846.0 84464.6 9394.0 9421.0 9458.0 9487.2 9555.2 21107.5 21718.0 21840.7 21895.9 21897.9 7224.9 7303.2 7304.7 7308.9 7358.1 3933.1 3969.1 3984.8 4055.6 4077.9 3410.5 3424.5 3467.5 3480.0 3481.5 622.8 624.0 627.7 629.2 633.2 564.3 592.4 596.9 597.8 603.5 6991.3 7994.8 8152.2 8226.0 8446.3 572.5 581.1 597.2 598.5 607.1 3778.1 3822.8 3870.5 3876.3 4068.7 93714.0 96178.5 96311.5 96339.0 98317.0 80613.0 81480.6 82323.6 82438.6 82544.2 64259.7 65223.7 65607.7 66079.7 66230.3 7624.5 7784.5 8044.2 8144.7 8201.3 47753.8 47792.5 48516.8 48855.0 49780.3 61499.4 61573.8 63328.7 63880.4 64104.4 10810.4 10897.1 10934.6 10998.5 11370.0 11907.7 12024.8 12046.2 12333.8 12446.2 11228.0 11375.1 11562.0 11794.9 11962.3 10922.5 11273.5 11378.6 11415.2 12124.5 11073.9 11173.0 11247.6 11303.7 12020.2 9354.0 9466.5 9560.4 9746.9 9772.9 8541.9 8947.3 9408.1 9511.0 9837.9 03:28:36 INFO - PROCESS | 1680 | -------- Summary: end -------- 03:28:36 INFO - PROCESS | 1680 | 03:28:37 INFO - TEST-INFO | 1680: exit 0 03:28:37 INFO - TEST-OK | dromaeo_css | took 1111852ms 03:28:37 INFO - TEST-START | kraken 03:28:37 INFO - operating with platform_type : mac_ 03:28:37 INFO - Initialising browser for kraken test... 03:28:37 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmp6N9wfc/profile http://localhost:49278/getInfo.html) 03:28:40 INFO - PROCESS | 1690 | __metrics Screen width/height:1600/1200 03:28:40 INFO - PROCESS | 1690 | colorDepth:24 03:28:40 INFO - PROCESS | 1690 | Browser inner width/height: 1024/689 03:28:40 INFO - PROCESS | 1690 | __metrics 03:28:40 ERROR - PROCESS | 1690 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 03:28:40 INFO - TEST-INFO | 1690: exit 0 03:28:40 INFO - Browser initialized. 03:28:40 INFO - Running cycle 1/1 for kraken test... 03:28:40 INFO - TEST-INFO | started process 1693 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmp6N9wfc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:28:52 INFO - PROCESS | 1693 | RSS: Main: 221372416 03:28:52 INFO - PROCESS | 1693 | 03:28:55 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:28:56 INFO - PROCESS | 1693 | RSS: Main: 233246720 03:28:56 INFO - PROCESS | 1693 | 03:28:59 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:28:59 INFO - PROCESS | 1693 | RSS: Main: 242077696 03:28:59 INFO - PROCESS | 1693 | 03:29:03 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:29:03 INFO - PROCESS | 1693 | RSS: Main: 244985856 03:29:03 INFO - PROCESS | 1693 | 03:29:06 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:29:06 INFO - PROCESS | 1693 | RSS: Main: 247136256 03:29:06 INFO - PROCESS | 1693 | 03:29:08 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:29:09 INFO - PROCESS | 1693 | RSS: Main: 247812096 03:29:09 INFO - PROCESS | 1693 | 03:29:12 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:29:12 INFO - PROCESS | 1693 | RSS: Main: 247488512 03:29:12 INFO - PROCESS | 1693 | 03:29:15 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:29:16 INFO - PROCESS | 1693 | RSS: Main: 247726080 03:29:16 INFO - PROCESS | 1693 | 03:29:19 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:29:20 INFO - PROCESS | 1693 | RSS: Main: 247881728 03:29:20 INFO - PROCESS | 1693 | 03:29:21 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:29:22 INFO - PROCESS | 1693 | RSS: Main: 247484416 03:29:22 INFO - PROCESS | 1693 | 03:29:24 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:29:25 INFO - PROCESS | 1693 | RSS: Main: 247517184 03:29:25 INFO - PROCESS | 1693 | 03:29:27 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:29:28 INFO - PROCESS | 1693 | RSS: Main: 247857152 03:29:28 INFO - PROCESS | 1693 | 03:29:30 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:29:31 INFO - PROCESS | 1693 | RSS: Main: 247902208 03:29:31 INFO - PROCESS | 1693 | 03:29:34 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:29:35 INFO - PROCESS | 1693 | RSS: Main: 247853056 03:29:35 INFO - PROCESS | 1693 | 03:29:36 INFO - PROCESS | 1693 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:29:37 INFO - PROCESS | 1693 | RSS: Main: 249237504 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | __start_tp_report 03:29:37 INFO - PROCESS | 1693 | _x_x_mozilla_page_load 03:29:37 INFO - PROCESS | 1693 | _x_x_mozilla_page_load_details 03:29:37 INFO - PROCESS | 1693 | |i|pagename|runs| 03:29:37 INFO - PROCESS | 1693 | |0;ai-astar;114;121;92;166;177;163;109;116;116;119 03:29:37 INFO - PROCESS | 1693 | |1;audio-beat-detection;170;109;181;184;152;156;128;150;198;120 03:29:37 INFO - PROCESS | 1693 | |2;audio-dft;192;195;196;226;273;185;285;285;200;201 03:29:37 INFO - PROCESS | 1693 | |3;audio-fft;66;117;105;143;56;56;59;73;138;57 03:29:37 INFO - PROCESS | 1693 | |4;audio-oscillator;82;86;135;95;117;120;160;79;79;119 03:29:37 INFO - PROCESS | 1693 | |5;imaging-gaussian-blur;89;87;90;90;89;87;90;101;134;88 03:29:37 INFO - PROCESS | 1693 | |6;imaging-darkroom;142;121;96;95;95;95;119;128;95;96 03:29:37 INFO - PROCESS | 1693 | |7;imaging-desaturate;152;147;91;92;99;89;120;114;90;127 03:29:37 INFO - PROCESS | 1693 | |8;json-parse-financial;56;58;67;65;66;66;74;108;53;78 03:29:37 INFO - PROCESS | 1693 | |9;json-stringify-tinderbox;59;75;91;79;80;73;65;66;70;67 03:29:37 INFO - PROCESS | 1693 | |10;stanford-crypto-aes;82;80;81;83;85;89;85;82;84;87 03:29:37 INFO - PROCESS | 1693 | |11;stanford-crypto-ccm;152;165;144;156;152;114;162;146;175;152 03:29:37 INFO - PROCESS | 1693 | |12;stanford-crypto-pbkdf2;198;172;202;226;191;176;168;169;168;166 03:29:37 INFO - PROCESS | 1693 | |13;stanford-crypto-sha256-iterative;67;68;67;67;69;69;64;68;67;69 03:29:37 INFO - PROCESS | 1693 | __end_tp_report 03:29:37 INFO - PROCESS | 1693 | __start_cc_report 03:29:37 INFO - PROCESS | 1693 | _x_x_mozilla_cycle_collect,1086 03:29:37 INFO - PROCESS | 1693 | __end_cc_report 03:29:37 INFO - PROCESS | 1693 | __startTimestamp1464431377027__endTimestamp 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | ------- Summary: start ------- 03:29:37 INFO - PROCESS | 1693 | Number of tests: 14 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#0] ai-astar Cycles:10 Average:129.30 Median:142.00 stddev:28.53 (20.1%) stddev-sans-first:29.72 03:29:37 INFO - PROCESS | 1693 | Values: 114.0 121.0 92.0 166.0 177.0 163.0 109.0 116.0 116.0 119.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#1] audio-beat-detection Cycles:10 Average:154.80 Median:163.00 stddev:29.24 (17.9%) stddev-sans-first:30.49 03:29:37 INFO - PROCESS | 1693 | Values: 170.0 109.0 181.0 184.0 152.0 156.0 128.0 150.0 198.0 120.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#2] audio-dft Cycles:10 Average:223.80 Median:213.50 stddev:40.99 (19.2%) stddev-sans-first:41.83 03:29:37 INFO - PROCESS | 1693 | Values: 192.0 195.0 196.0 226.0 273.0 185.0 285.0 285.0 200.0 201.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#3] audio-fft Cycles:10 Average:87.00 Median:56.50 stddev:35.28 (62.4%) stddev-sans-first:36.60 03:29:37 INFO - PROCESS | 1693 | Values: 66.0 117.0 105.0 143.0 56.0 56.0 59.0 73.0 138.0 57.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#4] audio-oscillator Cycles:10 Average:107.20 Median:79.00 stddev:27.45 (34.8%) stddev-sans-first:27.56 03:29:37 INFO - PROCESS | 1693 | Values: 82.0 86.0 135.0 95.0 117.0 120.0 160.0 79.0 79.0 119.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:89.00 stddev:14.45 (16.2%) stddev-sans-first:15.19 03:29:37 INFO - PROCESS | 1693 | Values: 89.0 87.0 90.0 90.0 89.0 87.0 90.0 101.0 134.0 88.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:95.00 stddev:17.67 (18.6%) stddev-sans-first:13.88 03:29:37 INFO - PROCESS | 1693 | Values: 142.0 121.0 96.0 95.0 95.0 95.0 119.0 128.0 95.0 96.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#7] imaging-desaturate Cycles:10 Average:112.10 Median:89.50 stddev:23.90 (26.7%) stddev-sans-first:20.53 03:29:37 INFO - PROCESS | 1693 | Values: 152.0 147.0 91.0 92.0 99.0 89.0 120.0 114.0 90.0 127.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#8] json-parse-financial Cycles:10 Average:69.10 Median:66.00 stddev:15.67 (23.7%) stddev-sans-first:15.89 03:29:37 INFO - PROCESS | 1693 | Values: 56.0 58.0 67.0 65.0 66.0 66.0 74.0 108.0 53.0 78.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:74.00 stddev:9.22 (12.5%) stddev-sans-first:8.38 03:29:37 INFO - PROCESS | 1693 | Values: 59.0 75.0 91.0 79.0 80.0 73.0 65.0 66.0 70.0 67.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.50 stddev:2.78 (3.3%) stddev-sans-first:2.87 03:29:37 INFO - PROCESS | 1693 | Values: 82.0 80.0 81.0 83.0 85.0 89.0 85.0 82.0 84.0 87.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:154.00 stddev:16.19 (10.5%) stddev-sans-first:17.17 03:29:37 INFO - PROCESS | 1693 | Values: 152.0 165.0 144.0 156.0 152.0 114.0 162.0 146.0 175.0 152.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:183.50 stddev:20.00 (10.9%) stddev-sans-first:20.52 03:29:37 INFO - PROCESS | 1693 | Values: 198.0 172.0 202.0 226.0 191.0 176.0 168.0 169.0 168.0 166.0 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - PROCESS | 1693 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.51 (2.2%) stddev-sans-first:1.59 03:29:37 INFO - PROCESS | 1693 | Values: 67.0 68.0 67.0 67.0 69.0 69.0 64.0 68.0 67.0 69.0 03:29:37 INFO - PROCESS | 1693 | -------- Summary: end -------- 03:29:37 INFO - PROCESS | 1693 | 03:29:37 INFO - TEST-INFO | 1693: exit 0 03:29:38 INFO - TEST-OK | kraken | took 60726ms 03:29:38 INFO - SUITE-END | took 1172s 03:29:38 INFO - Completed test suite (00:19:32) 03:29:38 INFO - javascript benchmark 03:29:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3504.579450465944, 3543.2204914918725, 3580.677739003492, 3601.7298702597304, 3616.168281295302, 5738.713064346776, 5771.047777116771, 5775.740091695873, 5813.098969659699, 5828.0966450200285, 5703.629264097831, 5807.709614519278, 5820.767169313233, 5845.678487683176, 5859.296884373871, 4299.871003869889, 5352.384475785296, 5355.732213389334, 5370.838874833761, 5384.3807962084275, 5252.684838909678, 5337.2527846101575, 5383.596230282725, 5437.238786569864, 5458.972705136469, 5345.518903298703, 5391.919121213185, 5398.0, 5408.4591540845995, 5431.402545719968, 5368.0605893968595, 5391.676499410048, 5401.999999999961, 5405.135178371481, 5454.727263636842, 4274.5939135782055, 5124.92312615311, 5303.416624171338, 5310.893782124374, 5325.920111198335, 5580.804671836507, 5637.0, 5659.151127330892, 5661.122526008476, 5710.685912274823, 6260.937390626081, 6272.56092073555, 6342.746290148389, 6345.49236061114, 6346.936530634681, 6215.7513699451965, 6255.186825712673, 6261.311255761862, 6261.749530018794, 6290.842728931768, 4777.235642297285, 5874.794382196603, 5900.203472531153, 5908.231929849135, 5912.231409916726, 6208.00671892495, 6346.2067241594805, 6364.522660800459, 6395.616263024233, 6401.775937842153, 5193.454687257859, 5297.629165958423, 6134.7121296814075, 6161.938380616226, 6271.498280137578, 5703.916255911364, 5934.19888315072, 6187.16473276102, 6321.1150438938585, 6333.619982801047, 5232.2674825524455, 6138.600990935575, 6261.749530018748, 6287.842803929939, 6305.905411418832, 5057.69653820772, 5346.278252435949, 5371.248025276464, 6188.81433556994, 6299.0866324382705, 4988.326575912205, 4989.90020199594, 5004.274380214849, 5030.748462576857, 5039.647224694236, 4414.55854414556, 4437.64498840092, 4479.641628669699, 4496.100779844044, 4520.095980803852, 5727.398623144593, 5788.623739456922, 5809.854753631109, 5828.708564571754, 5870.823875283749, 6291.622502649856, 6294.402031806971, 6299.307076221612, 6311.652859092794, 6313.684315784192, 5636.323641163087, 5793.478586927113, 5826.76692932278, 5830.300363956268, 5849.151872978395, 5374.516293533601, 5475.123980163155, 5478.233047373371, 5479.506844384025, 5505.064139096283, 6376.936230637727, 6469.644169570719, 6569.146011018633, 6574.4411725002, 6582.703778330082, 6253.624782513064, 6283.465905398092, 6314.494840412664, 6330.208723909512, 6342.175517182921, 5860.2967643883, 6239.970404883144, 6507.934920650733, 6538.542302038812, 6555.606663600009, 6233.532485063729, 6245.188125543832, 6261.029540421242, 6264.843378915563, 6264.968675156595, 6279.340669229665, 6290.21372328459, 6292.182016337847, 6302.180716507009, 6309.148264984168, 5102.821401250939, 5800.868830577997, 6023.969880150571, 6223.284322302993, 6232.25212974446, 5742.310922689304, 6173.6913154343, 6207.689615519296, 6212.81361559154, 6215.502759779297, 5497.725113744217, 5941.702914854326, 6060.848478788065, 6074.453299203093, 6075.969620151871, 4965.528274813887, 5433.347998240237, 5789.363170051375, 6129.350851464913, 6170.6914654268, 4948.727819969936, 6087.543434242361, 6100.664463454553, 6106.816795496142, 6132.41742034506], "value": 5704.765204250749, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.3186321508471, 540.4136511884604, 580.0747807247438, 596.4453058655452, 610.9949133675104, 34498.827505862464, 37384.43923341152, 37450.62549374506, 37486.8125659372, 37505.624943750554, 41329.79335103328, 41389.79305103478, 41648.37527437083, 41904.0, 41961.0, 9523.571439285213, 9645.95177024114, 9745.951270243675, 9757.268204884627, 9761.121499065082, 9531.904680953172, 9555.808883822354, 9564.713058608253, 9615.759606009835, 9696.933337332923, 9550.331476796628, 9574.13832755052, 9633.0, 9677.90322096777, 9679.08048735369, 9613.615455381776, 9676.322657413984, 9693.612255509772, 9706.70879873605, 9721.465319407394, 9559.044095590454, 9702.90297097027, 9710.17463515602, 9724.465154416504, 9737.902620973771, 38331.0, 38479.422808657895, 38532.80733596314, 38540.80729596362, 38929.80535097334, 3102.798318109338, 3144.921376965571, 3213.228825081987, 3264.347130573895, 3670.382165605085, 27782.0, 28780.424391512053, 28783.0, 28945.565816512768, 28989.42021159586, 2798.622186004904, 2800.635917330754, 2804.4110736745306, 2816.1270006298114, 2908.8254704717574, 463638.0, 468681.0, 469075.6546217281, 469147.6542617299, 470297.0, 35301.29397412037, 36432.453513197324, 60697.69651151804, 61244.38755612387, 61307.38692613106, 35424.82287588545, 35616.82191589026, 35707.4643880342, 35775.4633680495, 35824.104397389754, 15247.847521524865, 15326.23368831554, 15458.381664733284, 15471.303791329417, 15480.458183963507, 12911.289879056741, 12998.480060797652, 13088.541901033608, 13129.818316351575, 13131.803022954664, 8070.314023307968, 8239.546824924686, 8243.876341854877, 8269.875951860728, 8280.0, 3454.654534546525, 3470.583529976419, 3484.3379757846437, 3487.616362200156, 3490.424080026818, 949.5726922884592, 955.5652178258875, 1004.6483730694345, 1049.5119769307157, 1072.056590200634, 24905.9202842855, 28455.57316640252, 29344.0, 29877.253068673457, 30359.392812143633, 1026.4200726589454, 1075.1184029096066, 1164.7088227943013, 1175.623800383886, 1185.436917464215, 15004.174770387515, 15263.618409539627, 15328.080315181125, 15459.149746764037, 15501.534953951399, 630858.0, 631410.0, 636037.0, 636770.0, 640780.0, 59225.11162332568, 61119.555217791676, 61231.081533777025, 61469.77060458855, 61494.0, 1286.691194113406, 1470.2575199524172, 1492.5447738439664, 1498.9956728991601, 1500.0624609619206, 29018.56472152877, 29405.411891762473, 29474.852625737592, 29614.555781663723, 29957.70042299549, 49309.506904930495, 51112.74443627758, 51641.0, 51939.0, 54067.72966135144, 418697.7195342133, 491611.0, 509140.9085909093, 556384.0, 559983.0, 26884.924603016443, 27378.863105684344, 38510.0, 38824.25290861975, 43947.0, 38333.42499862448, 41322.58677413187, 42889.35665965075, 43256.567434325254, 43629.47296844595, 34731.958041258804, 34925.9522214334, 35482.29035419329, 35815.28369432649, 36240.637593623724, 35723.82138089397, 37002.25995480022, 37031.814840925625, 37584.0, 37743.811280944516, 36847.815760921025, 37093.07267318231, 38417.46330146763, 38880.0, 39021.80489097536, 29726.0, 31846.522302165024, 32040.679593204703, 32583.348333033682, 33162.0, 24695.876520617283, 25009.49981000406, 25038.62442063334, 25338.0, 25372.619410709223], "value": 18560.801954725488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.36755494107837, 400.451381607198, 403.65084202165116, 404.6964776417687, 413.64013308421676, 15531.68936621267, 15576.766348504752, 20262.290819821308, 20463.48841278967, 20585.764854108744, 16224.594385140377, 19767.616266861325, 20184.192632294726, 20211.696824547642, 20915.790842091574, 5704.401037891024, 5828.970855145729, 5833.8833223335505, 5989.760409583611, 6076.149339092531, 5874.324452687932, 5914.88170236595, 5982.491488223506, 5996.100584912255, 5997.250343707036, 5672.404397538261, 5921.289445266552, 5938.079597662369, 6000.75996960121, 6001.849953751147, 5965.821025369246, 5983.940160598404, 6010.549208809357, 6012.9398706013035, 6033.245844269466, 5093.643444958854, 5223.294855194538, 5254.842354729364, 5269.130593452076, 5296.152615581488, 25761.484770304676, 25788.871055644602, 26127.0, 26349.3412664683, 26861.86569067142, 2443.169322430382, 2585.2115104893, 2625.1599488163797, 2703.3917368592106, 2733.1117386849155, 12948.611541653765, 14420.423183072664, 14653.70692586153, 14671.926640366837, 14697.853021469862, 1560.9531714048596, 1659.821526716033, 1715.7254839225664, 1780.4213630569986, 1825.2237011975265, 101771.49196336637, 105105.0, 106460.0, 106941.39587906188, 110600.0, 8906.554672266491, 11050.060744836617, 11462.828057579143, 11476.483558239732, 11772.882271177179, 11857.407129643483, 12845.165064270792, 13646.0, 14179.929100354639, 14228.14631122136, 7605.82109772975, 8492.490450572985, 8919.464832110094, 8931.910680893237, 9029.458232506071, 9154.771130721652, 9178.54107294646, 9189.989101198862, 9244.90755092454, 9269.44383337002, 6789.558678685869, 6856.485763567753, 6878.0, 6878.518503704793, 6910.412614927787, 1642.6386195037073, 1934.429343343717, 1961.8920959347372, 1963.7152612871055, 2011.7083022961588, 760.9916860160199, 887.4941283468361, 979.911807937289, 1038.8753349598096, 1045.1899777672365, 20233.392998210078, 21001.264955726478, 21279.574408512053, 21304.36086917395, 21354.78645213559, 1010.8736407949007, 1037.7198156497702, 1043.4991204221865, 1066.6746642274181, 1079.282277285612, 11272.66182014541, 11624.651260462031, 11625.12811539121, 11656.0, 11675.76648467026, 561086.0, 574866.0, 592691.0, 593798.0, 602987.0, 109781.0, 110394.0, 111181.44409277901, 113563.43218283856, 116783.0, 915.4736026784625, 1046.6232156423682, 1059.4808543813476, 1102.4267380961855, 1106.2975010868156, 18901.0, 19001.049947502845, 19173.80826191776, 19174.61650767005, 19182.90408548004, 31094.378112438077, 47642.0, 48823.267650984555, 49724.0, 51687.0, 69443.61112777615, 69887.65056174686, 70945.22636934118, 73060.26939730748, 73618.26381736329, 8149.7147599833725, 8177.959110204649, 8293.543855088028, 8423.4945903246, 8524.531150786766, 11958.103142264192, 12816.295103769382, 12935.900448461985, 12974.481020759442, 12986.220826750046, 10261.74345641365, 10661.307015043998, 10982.725431864268, 11010.889891101308, 11527.135464840338, 10850.0, 10865.782684346426, 10952.616658416917, 11306.49120789583, 11474.827877581676, 11312.20814543007, 11334.093272538343, 11567.826482602599, 11637.243579167327, 11934.104942129201, 8372.246497815226, 8377.329813615019, 8539.74380768578, 8595.925509311335, 8778.648854045767, 10467.214958877963, 11468.770624587294, 11627.953484186464, 11681.649550513499, 11702.824457632973], "value": 9727.174672475381, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1588.499622618875, 1610.3075677458692, 1867.0, 1918.9616207675856, 2000.7999200079985, 7593.658285377158, 7948.324392426651, 8564.186402291787, 8775.166359195882, 8958.238549723274, 8741.213290803826, 8742.562871856413, 8789.077146899563, 8853.380263381567, 8892.64429422824, 2343.3438637181575, 2352.306069709431, 2366.7869891709743, 2382.3924899150693, 2387.988060059697, 2446.889889954947, 2456.459578892641, 2471.2462698876834, 2486.2292689266296, 2487.340854673513, 2453.325335532734, 2471.258622413278, 2475.4677744284977, 2479.7396273391305, 2481.4292712676097, 2210.2706106984656, 2440.6461063145834, 2442.7068751749725, 2465.1248806673643, 2475.6905386826556, 2422.8425152365216, 2433.634954756783, 2447.730749617541, 2458.360826185187, 2473.591857343536, 7676.577788221702, 8832.425892317044, 8958.23854972328, 8967.789348437942, 9007.909920900773, 4620.584147426748, 5292.047431462335, 5406.432324605938, 5440.0, 5466.316710411199, 3503.1242189452637, 3549.378858699743, 3577.8926632201074, 3580.2839432113683, 3612.3858943979585, 899.1907283444951, 1041.8906014868405, 1053.9104176145038, 1054.2356791326304, 1061.8035663402295, 10913.0, 11166.720831979184, 11417.48621312043, 11615.825762613567, 11729.0, 4669.789859456333, 4791.0, 4798.328234047236, 4805.831795887128, 4820.68665536739, 10004.299699021, 10073.0, 10102.444365559964, 10110.595576177018, 10111.7472063199, 5300.363956325188, 5319.255304257407, 5350.277712508761, 5361.08861493547, 5370.0, 3847.8653247136217, 4406.933895991563, 4869.70781753096, 4915.238138088602, 4919.040787046563, 4047.4535937648043, 4065.308897487434, 4149.398337241083, 4175.352820312856, 4176.081262122329, 1359.3746876436753, 1379.648189711637, 1380.565121986571, 1380.675541247806, 1399.7900314952838, 792.6116203060574, 831.5177197225595, 875.2757068574897, 934.5887809363858, 955.9044095590385, 5664.461876121762, 5818.330891947396, 5907.042590524757, 6075.997460418981, 6078.969605151946, 944.3153713557616, 965.0446058402131, 966.8259713251682, 997.2271489595621, 1014.4927536231884, 3953.3081710700512, 4041.151358214749, 4058.512978442606, 4091.9181616367505, 4093.7748423836974, 10505.842412363821, 10548.208884333551, 10657.360558366523, 10781.460926953621, 10844.240903136862, 10429.843552346569, 10448.634297799541, 10591.311564748266, 10712.678619641423, 10784.946075269574, 8293.751187464386, 8348.707795227137, 8353.624086916103, 8445.282151017233, 8449.028361738356, 5901.704914754331, 5956.463918247378, 5989.910151347646, 6044.697765111815, 6088.056351265561, 9302.95348523253, 9399.248060155034, 9418.0, 9443.575039123394, 9478.38390504615, 9399.577019034297, 9635.518224088628, 9820.65627703027, 9826.361286516354, 9940.900590994286, 6812.727490900506, 7086.149662040382, 7211.350978411968, 7439.9256007439235, 7552.320291173821, 7779.883301750591, 7817.335526480086, 7868.25251601097, 7869.370450364072, 7930.444868859125, 6957.339052789976, 7047.436205103477, 7065.57606543609, 7141.178764442156, 7181.748638797617, 6932.168139823253, 6946.340097690711, 6986.371226589631, 7013.123359580052, 7047.823804404726, 6661.4004739573675, 6716.597004179765, 6741.932580674326, 6754.763583274562, 6839.008343790222, 4781.067691800065, 4812.230043193073, 4822.13201623697, 4847.854564363075, 4862.562369386631, 4928.901421971469, 4938.950610493849, 4964.801407943642, 4997.100521906092, 5116.9744151279], "value": 4594.977656970991, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.94543509450756, 315.9241781972326, 328.94079065768153, 330.12187581034453, 332.330354336013, 6429.292777794439, 6518.924377477711, 6588.308227636101, 6626.834329141774, 6682.331766823309, 5261.842144735654, 6571.605703657784, 6577.473802095833, 6624.072629831815, 6628.70170842312, 4881.170201065827, 4933.753312334369, 4936.234883593049, 4939.555440010389, 4940.1601727706375, 4930.4083509978855, 4934.1611925972675, 4934.185859333205, 4956.529129732669, 4969.726665033421, 4495.6628252881, 4583.968607063401, 4826.34844296019, 4867.634927380443, 4893.388326459192, 4599.195140850338, 4817.349657796205, 4867.3915760529935, 4897.975510122462, 4902.28916807061, 3378.814165220912, 4059.776712280823, 4066.8576599818866, 4066.9186616267807, 4081.9183616327805, 6289.0, 6311.179546658951, 6317.0, 6329.3354197809485, 6345.904811427832, 5052.747362631853, 5169.095408303532, 5631.549476041907, 5659.434056594308, 5696.6866822324755, 7095.396891264249, 7095.538789978635, 7118.857622847514, 7134.6789394476855, 7137.964995075685, 4557.817687292504, 4558.794854231567, 4559.430071241095, 4563.566461186182, 4581.0, 5704.600677952545, 7469.701211951516, 7476.1776204617445, 7531.548107113591, 7586.658600362997, 4516.1419330327135, 4611.262198048296, 4611.423572053493, 4619.0, 4648.697834640738, 4697.647676424282, 4701.506341834058, 4706.458757242893, 4712.2224832902875, 4725.810967561259, 5560.165975103686, 5569.387367389584, 5572.86067848307, 5582.692951887685, 5600.719964001783, 6847.383735463733, 7112.786616401516, 7127.607981561064, 7136.536125151849, 7146.03528523653, 1607.4856046065381, 1614.3058484851392, 1625.2848746551479, 1627.6337823989509, 1630.6331075507917, 1025.1132770153913, 1034.622362837574, 1045.7856139491387, 1046.853440518328, 1048.1614708233383, 6465.321141280192, 6530.967345163243, 6604.900926486107, 6633.104530888365, 6691.899621505681, 7533.773986780406, 7545.396368290523, 7551.358134558514, 7578.848423031509, 7595.430342724318, 5824.94175058244, 6683.064370988066, 6722.66386680664, 6725.293844146392, 6743.797686069328, 3744.5693745219105, 3993.1614360983936, 3994.6604538614542, 3996.6403023728003, 3998.260321840469, 7638.885416718754, 8010.799730006796, 8013.919860801318, 8034.116247212802, 8044.517328960164, 7717.922820771832, 7787.415943804124, 7793.103793063757, 7804.336631386395, 7820.335271501872, 6967.895481567781, 7954.801129971681, 7976.441649084624, 7987.68049278034, 8027.5584842834205, 7255.419566434778, 7520.548767073993, 7544.962275188589, 7550.622468876424, 7575.545467271982, 5876.823695289148, 7421.777346679608, 7439.0, 7450.8509829802015, 7457.179710231979, 7959.124496305286, 7972.561509117054, 7984.041914970241, 8000.199980002186, 8026.237507436784, 4743.051389722001, 4769.260764581495, 4817.542333478314, 4855.36880205571, 4859.732714700725, 7074.929250707427, 7301.525400848928, 7304.890426643711, 7330.633468326669, 7375.520591161558, 7066.470014748812, 7066.9752885832295, 7074.363307302162, 7085.114360704912, 7119.430445564239, 7047.260037696173, 7051.682674279567, 7066.364027237573, 7081.362677359062, 7090.680919358538, 6906.309369063053, 6908.792736217922, 6911.17065952089, 6918.308169183243, 6923.515353925177, 6087.908681369871, 6972.965135174291, 7085.50401471892, 7162.856742865009, 7189.676464559003, 6777.966110169615, 6950.131233595801, 7034.683439245144, 7038.648067596702, 7153.85692286141], "value": 5179.591737562508, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.2976820150137, 692.2281655953611, 737.3400806278379, 739.7115125101211, 769.2154002917025, 14847.183404912725, 14893.0, 15108.017978831382, 15114.622134446645, 15117.168555729431, 13491.797623035638, 13705.246211458365, 13887.513937012207, 13934.233617151052, 13942.442302307896, 753.3295367123264, 906.4923642760042, 915.9786837675977, 926.7219834049791, 926.7683079230193, 903.0969030969031, 916.0, 938.5776400619715, 944.0417975754615, 946.3375637054089, 986.0632399220733, 987.476637382185, 991.3704797453632, 991.920646348294, 994.3735446668625, 939.9436033837992, 945.4941606240628, 960.6589660670467, 979.5543027922279, 982.7150126463317, 696.6551556979277, 737.6827963975488, 738.3354980517523, 740.9555426674362, 741.1661880384568, 14737.263136843116, 14787.186704731343, 14982.0, 15016.624584385478, 15036.398544058224, 10642.361458312449, 12618.621441356774, 12628.242305461701, 12811.87188128116, 12889.419976101004, 10009.949950250202, 10048.145907597864, 10098.394096354243, 10116.54475548602, 10273.178145748323, 3032.272254658888, 3246.788958717699, 3317.834108294576, 3470.889315419067, 3499.7025252853537, 75358.62320688416, 80772.0, 81060.18939810585, 83846.0, 84464.57767711184, 9393.953030234805, 9420.95289523548, 9458.0, 9487.241020718326, 9555.23558115349, 21107.472313191985, 21718.0, 21840.672389914165, 21895.890520547615, 21897.890510547662, 7224.891626625605, 7303.233160518069, 7304.744333948286, 7308.890366644505, 7358.11702595692, 3933.0757272041046, 3969.106950936074, 3984.820683069269, 4055.5741647127215, 4077.857274995361, 3410.4884267360094, 3424.4520876659617, 3467.531883195736, 3480.0, 3481.5299934509026, 622.7540121651922, 623.9594426362272, 627.7363507326934, 629.1632129268063, 633.1926793338438, 564.3199944067389, 592.3780030967467, 596.8955432799243, 597.8415719834247, 603.514171092275, 6991.265917078736, 7994.760157195294, 8152.206514566786, 8226.0, 8446.322067594434, 572.4561666416837, 581.1428143488321, 597.1997523318773, 598.5002522893345, 607.1105829959131, 3778.1499162688174, 3822.7897465639658, 3870.5161854768153, 3876.341022026262, 4068.6541643960004, 93714.0, 96178.51910740402, 96311.51844240873, 96339.03660963301, 98317.0, 80613.0, 81480.59259703664, 82323.5883820577, 82438.58780706178, 82544.17455825485, 64259.67870160619, 65223.695526089214, 65607.67196163989, 66079.66960165264, 66230.33769662338, 7624.5425274483705, 7784.494007889695, 8044.235797599219, 8144.674213031412, 8201.343892488705, 47753.761231193625, 47792.522074778804, 48516.75741621269, 48855.0457981691, 49780.25329619986, 61499.385006151155, 61573.76852462836, 63328.73342533215, 63880.36119638744, 64104.35895640984, 10810.405427701551, 10897.128229741444, 10934.56261749521, 10998.505067271832, 11370.033547148254, 11907.702307442036, 12024.75950481003, 12046.156769026084, 12333.814992775295, 12446.190997585129, 11228.0, 11375.146863985068, 11561.959423651911, 11794.941025294818, 11962.342071186169, 10922.50848711794, 11273.492692828679, 11378.601748938747, 11415.200935934405, 12124.515019399125, 11073.944630276797, 11173.0, 11247.606333778278, 11303.717407064889, 12020.158588898694, 9353.953230234078, 9466.52667366615, 9560.426374417557, 9746.85379719319, 9772.9022709772, 8541.871871921801, 8947.328950328621, 9408.059194080537, 9510.95244523773, 9837.852432213665], "value": 6869.105044239066, "unit": "score"}], "name": "dromaeo_css", "value": 7431.14785509662, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 121.0, 92.0, 166.0, 177.0, 163.0, 109.0, 116.0, 116.0, 119.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 109.0, 181.0, 184.0, 152.0, 156.0, 128.0, 150.0, 198.0, 120.0], "value": 154.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 195.0, 196.0, 226.0, 273.0, 185.0, 285.0, 285.0, 200.0, 201.0], "value": 223.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 117.0, 105.0, 143.0, 56.0, 56.0, 59.0, 73.0, 138.0, 57.0], "value": 87.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 86.0, 135.0, 95.0, 117.0, 120.0, 160.0, 79.0, 79.0, 119.0], "value": 107.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 90.0, 89.0, 87.0, 90.0, 101.0, 134.0, 88.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 121.0, 96.0, 95.0, 95.0, 95.0, 119.0, 128.0, 95.0, 96.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 147.0, 91.0, 92.0, 99.0, 89.0, 120.0, 114.0, 90.0, 127.0], "value": 112.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 67.0, 65.0, 66.0, 66.0, 74.0, 108.0, 53.0, 78.0], "value": 69.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 75.0, 91.0, 79.0, 80.0, 73.0, 65.0, 66.0, 70.0, 67.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 81.0, 83.0, 85.0, 89.0, 85.0, 82.0, 84.0, 87.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 165.0, 144.0, 156.0, 152.0, 114.0, 162.0, 146.0, 175.0, 152.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 172.0, 202.0, 226.0, 191.0, 176.0, 168.0, 169.0, 168.0, 166.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 69.0, 69.0, 64.0, 68.0, 67.0, 69.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1645.2, "alertThreshold": 2.0}]} 03:29:38 INFO - Return code: 0 03:29:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:29:38 ERROR - # TBPL SUCCESS # 03:29:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:29:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:29:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:29:38 INFO - 2016-05-28 03:29:38.351 screenresolution[1698:13195] starting screenresolution argv=screenresolution get 03:29:38 INFO - 2016-05-28 03:29:38.354 screenresolution[1698:13195] Display 0: 1600x1200x32@60 03:29:38 INFO - 2016-05-28 03:29:38.361 screenresolution[1699:13198] starting screenresolution argv=screenresolution list 03:29:38 INFO - Available Modes on Display 0 03:29:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:29:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:29:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:29:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:29:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:29:38 INFO - Intel Iris: 03:29:38 INFO - Chipset Model: Intel Iris 03:29:38 INFO - Type: GPU 03:29:38 INFO - Bus: Built-In 03:29:38 INFO - VRAM (Dynamic, Max): 1536 MB 03:29:38 INFO - Vendor: Intel (0x8086) 03:29:38 INFO - Device ID: 0x0a2e 03:29:38 INFO - Revision ID: 0x0009 03:29:38 INFO - Displays: 03:29:38 INFO - Display: 03:29:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:29:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:29:38 INFO - Display Serial Number: 1600x1200 60 03:29:38 INFO - Main Display: Yes 03:29:38 INFO - Mirror: Off 03:29:38 INFO - Online: Yes 03:29:38 INFO - Rotation: Supported 03:29:38 INFO - Return code: 0 03:29:38 INFO - Running post-action listener: _resource_record_post_action 03:29:38 INFO - Running post-run listener: _resource_record_post_run 03:29:39 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 31698432; Write bytes: 657381376; Read time: 197; Write time: 1848 03:29:39 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342753792; Write bytes: 336799232; Read time: 26564; Write time: 664 03:29:39 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30776832; Write bytes: 301244928; Read time: 187; Write time: 1125 03:29:39 INFO - Running post-run listener: _upload_blobber_files 03:29:39 INFO - Blob upload gear active. 03:29:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:29:39 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 03:29:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:29:40 INFO - (blobuploader) - INFO - Open directory for files ... 03:29:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:29:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:29:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:40 INFO - (blobuploader) - INFO - Done attempting. 03:29:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:29:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:41 INFO - (blobuploader) - INFO - Done attempting. 03:29:41 INFO - (blobuploader) - INFO - Iteration through files over. 03:29:41 INFO - Return code: 0 03:29:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:29:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:29:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4df69459f81d2d5496eaec1a7643ee8e17648fb2b04a093441598d5a08f9a1d387c70cbe360213d557b084873a922cacc40f371cf833b415624bbe7b59123bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4efbaeb5338acdaeb830733aef45cc621c8604148fcc9466351b26a531c87442e9894062c39735476464f7c368b3ea25c639ab190c4a6bc8af739f717899fb1f"} 03:29:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:29:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:29:41 INFO - Contents: 03:29:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4df69459f81d2d5496eaec1a7643ee8e17648fb2b04a093441598d5a08f9a1d387c70cbe360213d557b084873a922cacc40f371cf833b415624bbe7b59123bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4efbaeb5338acdaeb830733aef45cc621c8604148fcc9466351b26a531c87442e9894062c39735476464f7c368b3ea25c639ab190c4a6bc8af739f717899fb1f"} 03:29:41 INFO - Running post-run listener: copy_logs_to_upload_dir 03:29:41 INFO - Copying logs to upload dir... 03:29:41 INFO - mkdir: /builds/slave/test/build/upload/logs 03:29:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1331.312528 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-05-28 03:29:41.998218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:29:42.002195) ========= 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.waKKfxvPNc/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.e8UmImICZf/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4df69459f81d2d5496eaec1a7643ee8e17648fb2b04a093441598d5a08f9a1d387c70cbe360213d557b084873a922cacc40f371cf833b415624bbe7b59123bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4efbaeb5338acdaeb830733aef45cc621c8604148fcc9466351b26a531c87442e9894062c39735476464f7c368b3ea25c639ab190c4a6bc8af739f717899fb1f"} build_url:https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010993 build_url: 'https://queue.taskcluster.net/v1/task/exYQfz-jThSjCJc2Onf53A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4df69459f81d2d5496eaec1a7643ee8e17648fb2b04a093441598d5a08f9a1d387c70cbe360213d557b084873a922cacc40f371cf833b415624bbe7b59123bbe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4efbaeb5338acdaeb830733aef45cc621c8604148fcc9466351b26a531c87442e9894062c39735476464f7c368b3ea25c639ab190c4a6bc8af739f717899fb1f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:29:42.032141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:29:42.032501) ========= 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.waKKfxvPNc/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.e8UmImICZf/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.005374 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:29:42.081530) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:29:42.081893) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:29:42.082216) ========= ========= Total master_lag: 0.19 =========