builder: jamun_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0021 starttime: 1497624086.14 results: success (0) buildid: 20170616055020 builduid: 110144609da540f287433043b211d556 revision: 6909cf296f7bb17b310191d70535796dd532af2d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.137972) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.138518) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.138845) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.166121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.166584) ========= 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.6ONNELHFCz/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.La3qu1NpNZ/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 --2017-06-16 07:41:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 61.1M=0s 2017-06-16 07:41:26 (61.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083182 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.272277) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.272614) ========= 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.6ONNELHFCz/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.La3qu1NpNZ/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.037130 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:26.330619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-16 07:41:26.331038) ========= bash -c 'python archiver_client.py mozharness --repo projects/jamun --rev 6909cf296f7bb17b310191d70535796dd532af2d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/jamun --rev 6909cf296f7bb17b310191d70535796dd532af2d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6ONNELHFCz/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.La3qu1NpNZ/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 2017-06-16 07:41:26,345 truncating revision to first 12 chars 2017-06-16 07:41:26,345 Setting DEBUG logging. 2017-06-16 07:41:26,345 attempt 1/10 2017-06-16 07:41:26,345 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/jamun/6909cf296f7b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-16 07:41:27,959 unpacking tar archive at: jamun-6909cf296f7b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.892143 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-16 07:41:28.246111) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:28.246553) ========= script_repo_revision: 6909cf296f7bb17b310191d70535796dd532af2d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:28.247046) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:28.247403) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-16 07:41:28.266292) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-16 07:41:28.266712) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Jamun', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Jamun'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6ONNELHFCz/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.La3qu1NpNZ/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:41:28 INFO - MultiFileLogger online at 20170616 07:41:28 in /builds/slave/test 07:41:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun 07:41:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:41:28 INFO - {'append_to_log': False, 07:41:28 INFO - 'base_work_dir': '/builds/slave/test', 07:41:28 INFO - 'blob_upload_branch': 'Jamun', 07:41:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:41:28 INFO - 'branch': 'Jamun', 07:41:28 INFO - 'buildbot_json_path': 'buildprops.json', 07:41:28 INFO - 'config_files': ('talos/mac_config.py',), 07:41:28 INFO - 'default_actions': ('clobber', 07:41:28 INFO - 'read-buildbot-config', 07:41:28 INFO - 'download-and-extract', 07:41:28 INFO - 'populate-webroot', 07:41:28 INFO - 'create-virtualenv', 07:41:28 INFO - 'install', 07:41:28 INFO - 'run-tests'), 07:41:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:41:28 INFO - 'download_minidump_stackwalk': True, 07:41:28 INFO - 'download_symbols': 'ondemand', 07:41:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:41:28 INFO - 'tooltool.py': '/tools/tooltool.py', 07:41:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:41:28 INFO - '/tools/misc-python/virtualenv.py')}, 07:41:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:41:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:41:28 INFO - 'gecko_profile': False, 07:41:28 INFO - 'gecko_profile_interval': 0, 07:41:28 INFO - 'installer_path': 'installer.exe', 07:41:28 INFO - 'log_level': 'info', 07:41:28 INFO - 'log_name': 'talos', 07:41:28 INFO - 'log_to_console': True, 07:41:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:41:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:41:28 INFO - 'opt_config_files': (), 07:41:28 INFO - 'pip_index': False, 07:41:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:41:28 INFO - 'cmd': ('bash', 07:41:28 INFO - '-c', 07:41:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:41:28 INFO - 'enabled': True, 07:41:28 INFO - 'halt_on_failure': False, 07:41:28 INFO - 'name': 'check_screen_resolution'},), 07:41:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:41:28 INFO - 'cmd': ('bash', 07:41:28 INFO - '-c', 07:41:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:41:28 INFO - 'enabled': True, 07:41:28 INFO - 'halt_on_failure': False, 07:41:28 INFO - 'name': 'check_screen_resolution'},), 07:41:28 INFO - 'run_cmd_checks_enabled': True, 07:41:28 INFO - 'suite': 'dromaeojs-e10s', 07:41:28 INFO - 'system_bits': '32', 07:41:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:41:28 INFO - 'title': 't-yosemite-r7-0021', 07:41:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:41:28 INFO - 'use_talos_json': True, 07:41:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:41:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:41:28 INFO - 'work_dir': 'build'} 07:41:28 INFO - [mozharness: 2017-06-16 14:41:28.383321Z] Running clobber step. 07:41:28 INFO - Running pre-action listener: _resource_record_pre_action 07:41:28 INFO - Running main action method: clobber 07:41:28 INFO - rmtree: /builds/slave/test/build 07:41:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:41:30 INFO - Running post-action listener: _resource_record_post_action 07:41:30 INFO - [mozharness: 2017-06-16 14:41:30.842028Z] Finished clobber step (success) 07:41:30 INFO - [mozharness: 2017-06-16 14:41:30.842152Z] Running read-buildbot-config step. 07:41:30 INFO - Running pre-action listener: _resource_record_pre_action 07:41:30 INFO - Running main action method: read_buildbot_config 07:41:30 INFO - Using buildbot properties: 07:41:30 INFO - { 07:41:30 INFO - "project": "", 07:41:30 INFO - "product": "firefox", 07:41:30 INFO - "script_repo_revision": "production", 07:41:30 INFO - "builddir": "jamun_yosemite_r7_devedition_test-dromaeojs-e10s", 07:41:30 INFO - "repository": "", 07:41:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition jamun talos dromaeojs-e10s", 07:41:30 INFO - "buildid": "20170616055020", 07:41:30 INFO - "pgo_build": "False", 07:41:30 INFO - "basedir": "/builds/slave/test", 07:41:30 INFO - "buildnumber": 0, 07:41:30 INFO - "slavename": "t-yosemite-r7-0021", 07:41:30 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:41:30 INFO - "platform": "yosemite_r7_devedition", 07:41:30 INFO - "scheduler": "tests-jamun-macosx64-devedition-talos", 07:41:30 INFO - "branch": "jamun", 07:41:30 INFO - "repo_path": "projects/jamun", 07:41:30 INFO - "revision": "6909cf296f7bb17b310191d70535796dd532af2d", 07:41:30 INFO - "stage_platform": "macosx64-devedition", 07:41:30 INFO - "builduid": "110144609da540f287433043b211d556", 07:41:30 INFO - "slavebuilddir": "test" 07:41:30 INFO - } 07:41:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:41:30 INFO - Running post-action listener: _resource_record_post_action 07:41:30 INFO - [mozharness: 2017-06-16 14:41:30.845389Z] Finished read-buildbot-config step (success) 07:41:30 INFO - [mozharness: 2017-06-16 14:41:30.845521Z] Running download-and-extract step. 07:41:30 INFO - Running pre-action listener: _resource_record_pre_action 07:41:30 INFO - Running main action method: download_and_extract 07:41:30 INFO - mkdir: /builds/slave/test/build/tests 07:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:30 INFO - trying https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:41:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:41:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 07:41:31 INFO - Downloaded 1287 bytes. 07:41:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:41:31 INFO - Using the following test package requirements: 07:41:31 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:41:31 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:41:31 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:41:31 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:41:31 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:41:31 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:41:31 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:41:31 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:41:31 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:41:31 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:41:31 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:41:31 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:41:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:41:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:41:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:41:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:41:32 INFO - Expected file size: 51796170 07:41:32 INFO - Obtained file size: 51796170 07:41:49 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 07:41:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:41:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:41:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:41:51 INFO - Expected file size: 51796170 07:41:51 INFO - Obtained file size: 51796170 07:42:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:42:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:42:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:42:07 INFO - Expected file size: 14404449 07:42:07 INFO - Obtained file size: 14404449 07:42:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:07 INFO - trying https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:42:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:42:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 07:42:09 INFO - Downloaded 74765737 bytes. 07:42:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:42:09 INFO - mkdir: /builds/slave/test/properties 07:42:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:42:09 INFO - Writing to file /builds/slave/test/properties/build_url 07:42:09 INFO - Contents: 07:42:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:42:10 INFO - Running post-action listener: _resource_record_post_action 07:42:10 INFO - Running post-action listener: set_extra_try_arguments 07:42:10 INFO - [mozharness: 2017-06-16 14:42:10.493877Z] Finished download-and-extract step (success) 07:42:10 INFO - [mozharness: 2017-06-16 14:42:10.494031Z] Running populate-webroot step. 07:42:10 INFO - Running pre-action listener: _resource_record_pre_action 07:42:10 INFO - Running main action method: populate_webroot 07:42:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:42:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:42:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:42:10 INFO - '--tppagecycles', 07:42:10 INFO - '7'], 07:42:10 INFO - 'tests': ['tsvgm']}}, 07:42:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:42:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:42:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:42:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:42:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:42:10 INFO - 'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:42:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:42:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:42:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:42:10 INFO - 'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['damp', 'tps']}, 07:42:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:42:10 INFO - 'tests': ['damp', 'tps']}, 07:42:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['dromaeo_dom']}, 07:42:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:42:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['basic_compositor_video']}, 07:42:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:42:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['a11yr', 07:42:10 INFO - 'ts_paint', 07:42:10 INFO - 'tpaint', 07:42:10 INFO - 'sessionrestore', 07:42:10 INFO - 'sessionrestore_no_auto_restore', 07:42:10 INFO - 'tabpaint']}, 07:42:10 INFO - 'other-e10s': {'tests': ['a11yr', 07:42:10 INFO - 'ts_paint', 07:42:10 INFO - 'tpaint', 07:42:10 INFO - 'sessionrestore', 07:42:10 INFO - 'sessionrestore_no_auto_restore', 07:42:10 INFO - 'tabpaint']}, 07:42:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:42:10 INFO - 'ts_paint', 07:42:10 INFO - 'tpaint', 07:42:10 INFO - 'sessionrestore', 07:42:10 INFO - 'sessionrestore_no_auto_restore', 07:42:10 INFO - 'tabpaint']}, 07:42:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:42:10 INFO - 'ts_paint', 07:42:10 INFO - 'tpaint', 07:42:10 INFO - 'sessionrestore', 07:42:10 INFO - 'sessionrestore_no_auto_restore', 07:42:10 INFO - 'tabpaint']}, 07:42:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['a11yr', 07:42:10 INFO - 'ts_paint', 07:42:10 INFO - 'tpaint', 07:42:10 INFO - 'sessionrestore', 07:42:10 INFO - 'sessionrestore_no_auto_restore', 07:42:10 INFO - 'tabpaint']}, 07:42:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['a11yr', 07:42:10 INFO - 'ts_paint', 07:42:10 INFO - 'tpaint', 07:42:10 INFO - 'sessionrestore', 07:42:10 INFO - 'sessionrestore_no_auto_restore', 07:42:10 INFO - 'tabpaint']}, 07:42:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['tsvgx', 07:42:10 INFO - 'tsvgr_opacity', 07:42:10 INFO - 'tart', 07:42:10 INFO - 'tscrollx', 07:42:10 INFO - 'cart', 07:42:10 INFO - 'tsvg_static']}, 07:42:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:42:10 INFO - 'tsvgr_opacity', 07:42:10 INFO - 'tart', 07:42:10 INFO - 'tscrollx', 07:42:10 INFO - 'cart', 07:42:10 INFO - 'tsvg_static']}, 07:42:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:42:10 INFO - 'talos_options': ['--disable-e10s'], 07:42:10 INFO - 'tests': ['tp5o']}, 07:42:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:42:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:42:10 INFO - 'talos_options': ['--disable-e10s', 07:42:10 INFO - '--xperf_path', 07:42:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:42:10 INFO - 'tests': ['tp5n']}, 07:42:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:42:10 INFO - 'talos_options': ['--xperf_path', 07:42:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:42:10 INFO - 'tests': ['tp5n']}}, 07:42:10 INFO - 'talos.zip': {'path': '', 07:42:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:42:10 INFO - Running post-action listener: _resource_record_post_action 07:42:10 INFO - [mozharness: 2017-06-16 14:42:10.512746Z] Finished populate-webroot step (success) 07:42:10 INFO - [mozharness: 2017-06-16 14:42:10.512865Z] Running create-virtualenv step. 07:42:10 INFO - Running pre-action listener: _resource_record_pre_action 07:42:10 INFO - Running main action method: create_virtualenv 07:42:10 INFO - Creating virtualenv /builds/slave/test/build/venv 07:42:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:42:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:42:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:42:10 INFO - Using real prefix '/tools/python27' 07:42:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:42:11 INFO - Installing distribute.............................................................................................................................................................................................done. 07:42:14 INFO - Installing pip.................done. 07:42:14 INFO - Return code: 0 07:42:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:42:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:42:14 INFO - Reading from file tmpfile_stdout 07:42:14 INFO - Output received: 07:42:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:42:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:14 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:14 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:42:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:14 INFO - 'HOME': '/Users/cltbld', 07:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:14 INFO - 'LOGNAME': 'cltbld', 07:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:14 INFO - 'MOZ_NO_REMOTE': '1', 07:42:14 INFO - 'NO_EM_RESTART': '1', 07:42:14 INFO - 'PAGER': '/bin/cat', 07:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:14 INFO - 'PWD': '/builds/slave/test', 07:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:14 INFO - 'SHELL': '/bin/bash', 07:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:14 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:14 INFO - 'USER': 'cltbld', 07:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:14 INFO - 'XPC_FLAGS': '0x0', 07:42:14 INFO - 'XPC_SERVICE_NAME': '0', 07:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:42:14 INFO - Cleaning up... 07:42:14 INFO - Return code: 0 07:42:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:14 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:14 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:42:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:14 INFO - 'HOME': '/Users/cltbld', 07:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:14 INFO - 'LOGNAME': 'cltbld', 07:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:14 INFO - 'MOZ_NO_REMOTE': '1', 07:42:14 INFO - 'NO_EM_RESTART': '1', 07:42:14 INFO - 'PAGER': '/bin/cat', 07:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:14 INFO - 'PWD': '/builds/slave/test', 07:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:14 INFO - 'SHELL': '/bin/bash', 07:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:14 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:14 INFO - 'USER': 'cltbld', 07:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:14 INFO - 'XPC_FLAGS': '0x0', 07:42:14 INFO - 'XPC_SERVICE_NAME': '0', 07:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:15 INFO - Downloading/unpacking psutil>=3.1.1 07:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:42:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:42:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:42:16 INFO - Installing collected packages: psutil 07:42:16 INFO - Running setup.py install for psutil 07:42:17 INFO - building 'psutil._psutil_osx' extension 07:42:17 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 07:42:17 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 07:42:17 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 07:42:17 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 07:42:17 INFO - building 'psutil._psutil_posix' extension 07:42:17 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 07:42:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:42:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:42:17 INFO - ^ 07:42:17 INFO - 1 warning generated. 07:42:17 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 07:42:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:42:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:42:18 INFO - Successfully installed psutil 07:42:18 INFO - Cleaning up... 07:42:18 INFO - Return code: 0 07:42:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:18 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:18 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:18 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:18 INFO - 'HOME': '/Users/cltbld', 07:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:18 INFO - 'LOGNAME': 'cltbld', 07:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:18 INFO - 'MOZ_NO_REMOTE': '1', 07:42:18 INFO - 'NO_EM_RESTART': '1', 07:42:18 INFO - 'PAGER': '/bin/cat', 07:42:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:18 INFO - 'PWD': '/builds/slave/test', 07:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:18 INFO - 'SHELL': '/bin/bash', 07:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:18 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:18 INFO - 'USER': 'cltbld', 07:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:18 INFO - 'XPC_FLAGS': '0x0', 07:42:18 INFO - 'XPC_SERVICE_NAME': '0', 07:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:42:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:42:19 INFO - Installing collected packages: mozsystemmonitor 07:42:19 INFO - Running setup.py install for mozsystemmonitor 07:42:20 INFO - Successfully installed mozsystemmonitor 07:42:20 INFO - Cleaning up... 07:42:20 INFO - Return code: 0 07:42:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:20 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:20 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:20 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:20 INFO - 'HOME': '/Users/cltbld', 07:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:20 INFO - 'LOGNAME': 'cltbld', 07:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:20 INFO - 'MOZ_NO_REMOTE': '1', 07:42:20 INFO - 'NO_EM_RESTART': '1', 07:42:20 INFO - 'PAGER': '/bin/cat', 07:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:20 INFO - 'PWD': '/builds/slave/test', 07:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:20 INFO - 'SHELL': '/bin/bash', 07:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:20 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:20 INFO - 'USER': 'cltbld', 07:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:20 INFO - 'XPC_FLAGS': '0x0', 07:42:20 INFO - 'XPC_SERVICE_NAME': '0', 07:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:20 INFO - Downloading/unpacking jsonschema==2.5.1 07:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:42:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:21 INFO - Downloading functools32-3.2.3-2.tar.gz 07:42:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:42:22 INFO - warning: no files found matching '*.txt' 07:42:22 INFO - no previously-included directories found matching 'build' 07:42:22 INFO - no previously-included directories found matching 'dist' 07:42:22 INFO - no previously-included directories found matching '.git*' 07:42:22 INFO - Installing collected packages: jsonschema, functools32 07:42:22 INFO - Running setup.py install for functools32 07:42:22 INFO - warning: no files found matching '*.txt' 07:42:22 INFO - no previously-included directories found matching 'build' 07:42:22 INFO - no previously-included directories found matching 'dist' 07:42:22 INFO - no previously-included directories found matching '.git*' 07:42:22 INFO - Successfully installed jsonschema functools32 07:42:22 INFO - Cleaning up... 07:42:22 INFO - Return code: 0 07:42:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:22 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:22 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:22 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:42:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:42:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:22 INFO - 'HOME': '/Users/cltbld', 07:42:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:22 INFO - 'LOGNAME': 'cltbld', 07:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:22 INFO - 'MOZ_NO_REMOTE': '1', 07:42:22 INFO - 'NO_EM_RESTART': '1', 07:42:22 INFO - 'PAGER': '/bin/cat', 07:42:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:22 INFO - 'PWD': '/builds/slave/test', 07:42:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:22 INFO - 'SHELL': '/bin/bash', 07:42:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:22 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:22 INFO - 'USER': 'cltbld', 07:42:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:22 INFO - 'XPC_FLAGS': '0x0', 07:42:22 INFO - 'XPC_SERVICE_NAME': '0', 07:42:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:42:23 INFO - Cleaning up... 07:42:23 INFO - Return code: 0 07:42:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:23 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:23 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42: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/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 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:42:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:42:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:23 INFO - 'HOME': '/Users/cltbld', 07:42:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:23 INFO - 'LOGNAME': 'cltbld', 07:42:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:23 INFO - 'MOZ_NO_REMOTE': '1', 07:42:23 INFO - 'NO_EM_RESTART': '1', 07:42:23 INFO - 'PAGER': '/bin/cat', 07:42:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:23 INFO - 'PWD': '/builds/slave/test', 07:42:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:23 INFO - 'SHELL': '/bin/bash', 07:42:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:23 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:23 INFO - 'USER': 'cltbld', 07:42:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:23 INFO - 'XPC_FLAGS': '0x0', 07:42:23 INFO - 'XPC_SERVICE_NAME': '0', 07:42:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:24 INFO - Downloading/unpacking blobuploader==1.2.4 07:42: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 07:42: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 07:42: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 07:42: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 07:42:25 INFO - Downloading blobuploader-1.2.4.tar.gz 07:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:42:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:42:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:42: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 07:42: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 07:42: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 07:42: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 07:42:26 INFO - Downloading docopt-0.6.1.tar.gz 07:42:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:42:26 INFO - Installing collected packages: blobuploader, requests, docopt 07:42:26 INFO - Running setup.py install for blobuploader 07:42:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:42:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:42:27 INFO - Running setup.py install for requests 07:42:27 INFO - Running setup.py install for docopt 07:42:27 INFO - Successfully installed blobuploader requests docopt 07:42:27 INFO - Cleaning up... 07:42:27 INFO - Return code: 0 07:42:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:27 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:27 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:27 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/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 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:27 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:42:27 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:27 INFO - 'HOME': '/Users/cltbld', 07:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:27 INFO - 'LOGNAME': 'cltbld', 07:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:27 INFO - 'MOZ_NO_REMOTE': '1', 07:42:27 INFO - 'NO_EM_RESTART': '1', 07:42:27 INFO - 'PAGER': '/bin/cat', 07:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:27 INFO - 'PWD': '/builds/slave/test', 07:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:27 INFO - 'SHELL': '/bin/bash', 07:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:27 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:27 INFO - 'USER': 'cltbld', 07:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:27 INFO - 'XPC_FLAGS': '0x0', 07:42:27 INFO - 'XPC_SERVICE_NAME': '0', 07:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:27 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-G2vqT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-RFIOiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-GIIwVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-SlifeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Oyf18A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-NHRsJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-MwUMS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-eaWbxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-9KDXi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-sM_1Xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Tjoks4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-7HOkHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-LLxssv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-3ZGTQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-fAD9MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-X5phjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-_6122E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:42:29 INFO - Running setup.py install for manifestparser 07:42:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:42:29 INFO - Running setup.py install for mozcrash 07:42:30 INFO - Running setup.py install for mozdebug 07:42:30 INFO - Running setup.py install for mozdevice 07:42:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Running setup.py install for mozfile 07:42:30 INFO - Running setup.py install for mozhttpd 07:42:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Running setup.py install for mozinfo 07:42:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Running setup.py install for mozInstall 07:42:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:42:30 INFO - Running setup.py install for mozleak 07:42:31 INFO - Running setup.py install for mozlog 07:42:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:42:31 INFO - Running setup.py install for moznetwork 07:42:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:42:31 INFO - Running setup.py install for mozprocess 07:42:31 INFO - Running setup.py install for mozprofile 07:42:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:42:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:42:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:42:31 INFO - Running setup.py install for mozrunner 07:42:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:42:31 INFO - Running setup.py install for mozscreenshot 07:42:31 INFO - Running setup.py install for moztest 07:42:32 INFO - Running setup.py install for mozversion 07:42:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:42:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:42:32 INFO - Cleaning up... 07:42:32 INFO - Return code: 0 07:42:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:32 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:32 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:32 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/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 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:32 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:42:32 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:42:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:32 INFO - 'HOME': '/Users/cltbld', 07:42:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:32 INFO - 'LOGNAME': 'cltbld', 07:42:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:32 INFO - 'MOZ_NO_REMOTE': '1', 07:42:32 INFO - 'NO_EM_RESTART': '1', 07:42:32 INFO - 'PAGER': '/bin/cat', 07:42:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:32 INFO - 'PWD': '/builds/slave/test', 07:42:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:32 INFO - 'SHELL': '/bin/bash', 07:42:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:32 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:32 INFO - 'USER': 'cltbld', 07:42:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:32 INFO - 'XPC_FLAGS': '0x0', 07:42:32 INFO - 'XPC_SERVICE_NAME': '0', 07:42:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:32 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-4hzchm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:32 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-sCrVmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:32 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-EqvM4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:32 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-MfxRAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ysi1rD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ehL3pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-i1_JD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-61ckHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-lxYhVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-sPrRzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ipsx0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-4xNraU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:33 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-HuKaiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:34 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-h3gjNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:34 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-c3von5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:34 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-jOT0DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:34 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-7e_Qfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:42:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:35 INFO - Downloading blessings-1.6.tar.gz 07:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:42:35 INFO - Installing collected packages: blessings 07:42:35 INFO - Running setup.py install for blessings 07:42:36 INFO - Successfully installed blessings 07:42:36 INFO - Cleaning up... 07:42:36 INFO - Return code: 0 07:42:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:42:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:36 INFO - Reading from file tmpfile_stdout 07:42:36 INFO - Current package versions: 07:42:36 INFO - blessings == 1.6 07:42:36 INFO - blobuploader == 1.2.4 07:42:36 INFO - docopt == 0.6.1 07:42:36 INFO - functools32 == 3.2.3-2 07:42:36 INFO - jsonschema == 2.5.1 07:42:36 INFO - manifestparser == 1.1 07:42:36 INFO - mozInstall == 1.12 07:42:36 INFO - mozcrash == 1.0 07:42:36 INFO - mozdebug == 0.1 07:42:36 INFO - mozdevice == 0.49 07:42:36 INFO - mozfile == 1.2 07:42:36 INFO - mozhttpd == 0.7 07:42:36 INFO - mozinfo == 0.9 07:42:36 INFO - mozleak == 0.1 07:42:36 INFO - mozlog == 3.4 07:42:36 INFO - moznetwork == 0.27 07:42:36 INFO - mozprocess == 0.25 07:42:36 INFO - mozprofile == 0.28 07:42:36 INFO - mozrunner == 6.13 07:42:36 INFO - mozscreenshot == 0.1 07:42:36 INFO - mozsystemmonitor == 0.3 07:42:36 INFO - moztest == 0.8 07:42:36 INFO - mozversion == 1.4 07:42:36 INFO - psutil == 3.1.1 07:42:36 INFO - requests == 1.2.3 07:42:36 INFO - wsgiref == 0.1.2 07:42:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:36 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:36 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:36 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/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 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:36 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:42:36 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:36 INFO - 'HOME': '/Users/cltbld', 07:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:36 INFO - 'LOGNAME': 'cltbld', 07:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:36 INFO - 'MOZ_NO_REMOTE': '1', 07:42:36 INFO - 'NO_EM_RESTART': '1', 07:42:36 INFO - 'PAGER': '/bin/cat', 07:42:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:36 INFO - 'PWD': '/builds/slave/test', 07:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:36 INFO - 'SHELL': '/bin/bash', 07:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:36 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:36 INFO - 'USER': 'cltbld', 07:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:36 INFO - 'XPC_FLAGS': '0x0', 07:42:36 INFO - 'XPC_SERVICE_NAME': '0', 07:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:42:36 INFO - Cleaning up... 07:42:36 INFO - Return code: 0 07:42:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:36 INFO - error resolving pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:36 INFO - error resolving pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com (ignoring): 07:42:36 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/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 0x10b884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5aca4c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, '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.6ONNELHFCz/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.La3qu1NpNZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 07:42:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:36 INFO - 'HOME': '/Users/cltbld', 07:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:36 INFO - 'LOGNAME': 'cltbld', 07:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:36 INFO - 'MOZ_NO_REMOTE': '1', 07:42:36 INFO - 'NO_EM_RESTART': '1', 07:42:36 INFO - 'PAGER': '/bin/cat', 07:42:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:36 INFO - 'PWD': '/builds/slave/test', 07:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:36 INFO - 'SHELL': '/bin/bash', 07:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:36 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:36 INFO - 'USER': 'cltbld', 07:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:36 INFO - 'XPC_FLAGS': '0x0', 07:42:36 INFO - 'XPC_SERVICE_NAME': '0', 07:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:42:36 INFO - Cleaning up... 07:42:36 INFO - Return code: 0 07:42:36 INFO - Running post-action listener: _resource_record_post_action 07:42:36 INFO - Running post-action listener: _start_resource_monitoring 07:42:36 INFO - Starting resource monitoring. 07:42:36 INFO - [mozharness: 2017-06-16 14:42:36.990942Z] Finished create-virtualenv step (success) 07:42:36 INFO - [mozharness: 2017-06-16 14:42:36.991919Z] Running install step. 07:42:36 INFO - Running pre-action listener: _resource_record_pre_action 07:42:36 INFO - Running main action method: install 07:42:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:37 INFO - Reading from file tmpfile_stdout 07:42:37 INFO - Detecting whether we're running mozinstall >=1.0... 07:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:42:37 INFO - Reading from file tmpfile_stdout 07:42:37 INFO - Output received: 07:42:37 INFO - Usage: mozinstall [options] installer 07:42:37 INFO - Options: 07:42:37 INFO - -h, --help show this help message and exit 07:42:37 INFO - -d DEST, --destination=DEST 07:42:37 INFO - Directory to install application into. [default: 07:42:37 INFO - "/builds/slave/test"] 07:42:37 INFO - --app=APP Application being installed. [default: firefox] 07:42:37 INFO - mkdir: /builds/slave/test/build/application 07:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:42:57 INFO - Reading from file tmpfile_stdout 07:42:57 INFO - Output received: 07:42:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:42:57 INFO - Running post-action listener: _resource_record_post_action 07:42:57 INFO - [mozharness: 2017-06-16 14:42:57.468598Z] Finished install step (success) 07:42:57 INFO - [mozharness: 2017-06-16 14:42:57.468745Z] Running run-tests step. 07:42:57 INFO - Running pre-action listener: _resource_record_pre_action 07:42:57 INFO - Running main action method: run_tests 07:42:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:42:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:42:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:42:57 INFO - 2017-06-16 07:42:57.483 screenresolution[1646:7364] starting screenresolution argv=screenresolution get 07:42:57 INFO - 2017-06-16 07:42:57.486 screenresolution[1646:7364] Display 0: 1600x1200x32@60 07:42:57 INFO - 2017-06-16 07:42:57.492 screenresolution[1647:7366] starting screenresolution argv=screenresolution list 07:42:57 INFO - Available Modes on Display 0 07:42:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:42:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:42:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:42:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:42:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:42:57 INFO - Intel Iris: 07:42:57 INFO - Chipset Model: Intel Iris 07:42:57 INFO - Type: GPU 07:42:57 INFO - Bus: Built-In 07:42:57 INFO - VRAM (Dynamic, Max): 1536 MB 07:42:57 INFO - Vendor: Intel (0x8086) 07:42:57 INFO - Device ID: 0x0a2e 07:42:57 INFO - Revision ID: 0x0009 07:42:57 INFO - Displays: 07:42:57 INFO - Display: 07:42:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:42:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:42:57 INFO - Display Serial Number: 1600x1200 60 07:42:57 INFO - Main Display: Yes 07:42:57 INFO - Mirror: Off 07:42:57 INFO - Online: Yes 07:42:57 INFO - Rotation: Supported 07:42:57 INFO - Return code: 0 07:42:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:42:57 INFO - Python 2.7.3 07:42:57 INFO - Return code: 0 07:42:57 INFO - grabbing minidump binary from tooltool 07:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:57 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b662d30>, '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 0x10b589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b657be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:42:57 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 07:42:57 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 07:42:57 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:42:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:42:57 INFO - Return code: 0 07:42:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:42:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:42:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:42:57 INFO - ENV: RUST_BACKTRACE is now 1 07:42:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:42:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:42:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 07:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Jamun --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0021 --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 07:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6ONNELHFCz/Render', 07:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:57 INFO - 'HOME': '/Users/cltbld', 07:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:57 INFO - 'LOGNAME': 'cltbld', 07:42:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:42:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:57 INFO - 'MOZ_NO_REMOTE': '1', 07:42:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:42:57 INFO - 'NO_EM_RESTART': '1', 07:42:57 INFO - 'PAGER': '/bin/cat', 07:42:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:57 INFO - 'PWD': '/builds/slave/test', 07:42:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:57 INFO - 'RUST_BACKTRACE': '1', 07:42:57 INFO - 'SHELL': '/bin/bash', 07:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.La3qu1NpNZ/Listeners', 07:42:57 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 07:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:57 INFO - 'USER': 'cltbld', 07:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:57 INFO - 'XPC_FLAGS': '0x0', 07:42:57 INFO - 'XPC_SERVICE_NAME': '0', 07:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 07:42:58 INFO - mozversion application_buildid: 20170616055020 07:42:58 INFO - mozversion application_changeset: 6909cf296f7bb17b310191d70535796dd532af2d 07:42:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:42:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:42:58 INFO - mozversion application_name: Firefox 07:42:58 INFO - mozversion application_remotingname: firefox-dev 07:42:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/jamun 07:42:58 INFO - mozversion application_vendor: Mozilla 07:42:58 INFO - mozversion application_version: 54.0 07:42:58 INFO - mozversion platform_buildid: 20170616055020 07:42:58 INFO - mozversion platform_changeset: 6909cf296f7bb17b310191d70535796dd532af2d 07:42:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/jamun 07:42:58 INFO - mozversion platform_version: 54.0 07:42:58 INFO - using testdate: 1497624178 07:42:58 INFO - actual date: 1497624178 07:42:58 INFO - starting webserver on 'localhost:49230' 07:42:58 INFO - SUITE-START | Running 2 tests 07:42:58 INFO - TEST-START | dromaeo_css 07:42:58 INFO - operating with platform_type : mac_ 07:42:58 INFO - Initialising browser for dromaeo_css test... 07:42:58 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpL7tlRP/profile http://localhost:49230/getInfo.html) 07:43:01 INFO - PROCESS | 1657 | 2017-06-16 07:43:01.181 plugin-container[1660:7544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494b, name = 'com.apple.tsm.portname' 07:43:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:43:01 INFO - PROCESS | 1657 | 2017-06-16 07:43:01.182 plugin-container[1660:7544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 07:43:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:43:01 INFO - PROCESS | 1657 | 2017-06-16 07:43:01.182 plugin-container[1660:7544] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:43:01 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 07:43:01 INFO - PROCESS | 1657 | colorDepth:24 07:43:01 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 07:43:01 INFO - PROCESS | 1657 | __metrics 07:43:01 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 07:43:01 INFO - TEST-INFO | 1657: exit 0 07:43:01 INFO - Browser initialized. 07:43:01 INFO - Running cycle 1/1 for dromaeo_css test... 07:43:01 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpL7tlRP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:43:04 INFO - PROCESS | 1661 | 2017-06-16 07:43:04.177 plugin-container[1662:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 07:43:04 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:43:04 INFO - PROCESS | 1661 | 2017-06-16 07:43:04.177 plugin-container[1662:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 07:43:04 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:43:04 INFO - PROCESS | 1661 | 2017-06-16 07:43:04.177 plugin-container[1662:7672] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:43:13 INFO - PROCESS | 1661 | RSS: Main: 202588160 07:43:13 INFO - PROCESS | 1661 | 07:46:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 07:46:19 INFO - PROCESS | 1661 | RSS: Main: 215658496 07:46:19 INFO - PROCESS | 1661 | 07:49:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 07:49:22 INFO - PROCESS | 1661 | RSS: Main: 229449728 07:49:22 INFO - PROCESS | 1661 | 07:52:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 07:52:24 INFO - PROCESS | 1661 | RSS: Main: 231747584 07:52:24 INFO - PROCESS | 1661 | 07:55:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 07:55:27 INFO - PROCESS | 1661 | RSS: Main: 234893312 07:55:27 INFO - PROCESS | 1661 | 07:58:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 07:58:30 INFO - PROCESS | 1661 | RSS: Main: 236994560 07:58:30 INFO - PROCESS | 1661 | 08:01:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 08:01:34 INFO - PROCESS | 1661 | RSS: Main: 235208704 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | __start_tp_report 08:01:34 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 08:01:34 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 08:01:34 INFO - PROCESS | 1661 | |i|pagename|runs| 08:01:34 INFO - PROCESS | 1661 | |0;dojo.html;2970.554416837475;3237.7409807215427;3365.4615261558156;3497.807620580867;3526.999999999999;5421.590386499509;5655.462731040549;5825.475707186352;6035.245594300718;6079.027355623096;5594.216809646653;6101.69491525423;6108.175396321495;6115.724792384342;6163.383661633834;5447.291852059237;5520.751566179531;5541;5558.972205138969;5570.498655121039;5555.805546805863;5559.444055594448;5563.304586926634;5570.888582228353;5587.580931430139;5080.980292977078;5177.974110129444;5364.704941228231;5365.839024829242;5580.944190558083;5416.51251387377;5475.72621368932;5481.260029895953;5485.561155107602;5508.944910550883;5468.91796623051;5474.698891561003;5497.725113744297;5505.201745746885;5554.472325129106;6161.19904412428;6191.535634827406;6192.195014648112;6208.96895515524;6210.378962103799;5368.221607866876;6314.652694101823;6322.525810564226;6392.20097487814;6411.807645770635;6382.521310901655;6397.584157029778;6435.742570297182;6483.902741458882;6522.380373864476;6210.285817131043;6211.378862113753;6223.0354295084335;6229.53278504114;6266.937330626726;5801.796937107265;6080.0575910733905;6493.285738568754;6661.134052573231;6663.36698013688;6252.093446450239;6269.49844012487;6277.654728989949;6289.213848268966;6289.528285378524;5446.2375267462585;6468.838279043061;6500;6500.154979852589;6517.902231466532;6341.936580634135;6352.777652782131;6392.904106438407;6394.392532709385;6421.036844473273;6296.338884417162;6299.244090709144;6302.338254483305;6304.338044505261;6418.775342862978;4253.936190957138;5044.848654540369;5165.845024649267;5173.042987047408;5212.739363031909;4329.15581461612;4567.474740404829;4586.128635559233;4599.724016559017;4609.055143695535;4879.463259041502;5364.873376590882;5993.520518358521;6134.38656134383;6158.137860699506;5758.165066065397;6252.812415627538;6310.558260921784;6347.682615869188;6366.808995730135;5063.772130254148;6137.508999280047;6156.846078848064;6161.4146656067605;6175.413335733098;5676.801311954062;5705.657660540298;5714.657120572779;5716.885662286731;5731.226284451545;6350.555461117678;6436.13112229843;6544.738210471718;6676.56602320867;6681.76613818514;6454.41910228063;6455.0962865199845;6456.870862582816;6466.579672321284;6496.480281577557;6647.833804154935;6660.700268488026;6708.6310252936555;6711.731530738716;6714.362135597111;6269.3417191196095;6303.1175635412;6350.90473642905;6367.872642547216;6379.553431259767;6327.3988971047675;6340.778072767615;6341.048842673636;6472.773452929313;6482.416582507597;6304.810855674337;6336.144620476177;6346.2067241594805;6346.90479642815;6352.396522330371;6174.289956655042;6232.75068997235;6285.937140628535;6333.018382150774;6359;6046.123312119806;6146.815595532141;6249.281332646804;6318.368163183645;6375.298717141018;5280.709560974184;6223.222097237845;6309.3690630936535;6319.431251187415;6319.842003949938;5919.674417906885;6070.1501789750355;6111.743676692077;6216.564840461125;6314.242290925118 08:01:34 INFO - PROCESS | 1661 | |1;ext.html;704.1690804850276;768.7891570776026;838.2204549768717;971.6744890461699;977.8239916814979;33706.49440258395;34683.82658086709;43107.70676879691;43236.567634323685;43249.7837510812;41444.37833432493;41757;41785.16429671412;41856.16287674245;41874.58125418745;8053.275205231514;11011.83482247767;11278.928501792316;11431.828522572168;11456.656300311004;11086.667399978014;11181.944090279578;11201;11223;11236.887631123665;11183.105351571854;11234.719132021683;11243.60647377342;11260.21178517504;11260.887391126107;11176.161787865862;11179.217454778169;11179.552817887276;11195.832062519068;11204.327740335606;10952.288101273392;11002.834957475565;11011.174161937886;11019.724506887313;11049.558017679283;35376.46935295973;35575;35756.463653045226;35841.64158358409;35980;3855.363864962278;3869.5743468218475;3891.747036442622;3911.8239679214503;3932.016995751062;26160.607590886153;27222;27507;27903.302417439725;27926.441471170463;3005.8782504732358;3104.3480869017535;3128.8122712637314;3136;3185.9203519911957;375807.12096439616;376005.11997440114;376394.11802941083;376395;378349;46559.30161047587;46688.299675504895;46694.53305466902;46861.53138468572;47154.5284547157;40193;47845.76077119592;48492.5150748488;48630.78423039452;48645.51354486481;14213.50758170398;14343.780778633933;14357.6410589734;14388.352524136439;14404.567862964128;12137.453814578364;12269.141160118885;12318.876811231772;12330.75338493243;12346.382680865921;11234.157438192005;12260.26438413698;12263.0189584833;12274.815877761841;12395.814062789066;4455.9554404456185;4644.7213167210075;5135.12702840518;5723.513501352431;5803.47768700819;1056.8374787733585;1087.983680244797;1131.4455916600807;1134.9659510214706;1211.5884993982088;24349.51300974006;29075.836966521525;29379.265518361786;29590.260243493653;29611.815527378665;1019.725343320849;1151.401271338899;1170.309517384747;1183.5679976808576;1198.7183011992174;13110.016748743883;14398.640033999234;14488.058276212012;14845.554633361016;15018.774718379233;497905;501047;502215;502342;502587;59870;60012;60219;60286;60378;1352.4184600621631;1392.8398234202994;1393.0666453476397;1404.6769243073945;1406.7256884907345;24300.756992429848;27404.588931166447;27892;28136.859315703292;28472.715272847836;48934.510654893;49007;49025.019499609094;49122.75438622784;49284.26073608827;405262;484195;484377;484935;485467.5726621344;31411.371772563965;32111.197220069684;33640;33979.32041359109;34053.82973085218;40575.797121014206;40959.77120686384;41537.37693934654;41656.791716041225;41793.582064180184;28357.858210708815;28579.714202858537;28736.71263287424;28753;29209.269768255115;23493.530129396975;28195.71804281931;28302.43395132127;28688.713112868605;28794.28014299659;29479.557806633347;29986.800527978638;32310.515342270355;33150.668493314755;33358;22384.776152238268;26853.462930741665;26859.597106043817;29246.561301580918;30545.847270763505;23265.76734232704;24175.879120604284;24210.273691789276;24336.634950475403;24626 08:01:34 INFO - PROCESS | 1661 | |2;jquery.html;408.29161404962394;418.4664552695313;420.6992000719485;431.1764529748182;459.3729559151757;19700.60598788025;20673.96630168491;22098.779012209874;22232.444188895286;22295.888520557415;19993.300234491795;21283.57432851342;22488.212912548024;22601.773982260172;22768.31695049147;6746.224184218816;6895.862082758342;6908.654567271641;6915.135608048994;6934.965325173393;6802.659867006654;7523;7885.566293853835;8006.67973281068;8026;7766.301032907038;7941.28528432441;7954.443188976774;7979.441439099265;8016.318612917909;6908.689108990107;7762.301392874669;7950.3242224411;8019.398545109111;8049.315808156313;7517;7750.534967901944;7785.727499537546;7788.493747906425;7799.1031031430975;18857;25792.484150317076;26330.21009369722;26485.602715959278;26735;2877.4101309231764;2962.3482833776534;2981.1950773291305;2987.4323878463024;2989.7309242168094;13633;15741.92129039359;16458.259378328003;16868.325266989305;17041.659166816593;2047.7509168475474;2124.2989813361555;2164.134346261508;2171.7936796004356;2243.8317126215597;131891;141358;142686;162589;177864.22135778735;16773.24520396585;19887.801121988883;20086.799132008786;20479;20509.38471845847;32359.35281294361;32649.34701306008;32878.83560582182;32886.67113328884;32969.505457418156;10226.795464090677;10384.065434110815;10522.526486308134;10584.417857017937;10745.194110441747;8672.262857657044;8725.825483490295;8733.912660873437;8863.025067242597;8863.335249856287;9527.857082143773;9604.66383676568;9624.422534647943;9696.224302055818;9747.463889486004;3545.8936231913085;4185.3423076346735;4251.128518653669;4265.936010959838;4289.828406863753;988.0613417253638;1023.892491288404;1037.37650576847;1056.3133962924007;1059.8834128245887;21294.680579791315;21569.460763480725;21635.891820540797;21684.24105156312;21716.674249886266;848.9957550212209;1017.801528701896;1051.6476980211714;1057.7038429239826;1066.7119877632995;8871.423357481743;10632.840507392395;11055.557777688802;11101.5559377624;11131.666050018512;755655;791285;795437;815769;816734;158560;162237.9293180687;163695;167167;168394.47408288886;1168.415792103948;1175.2184797109996;1195.9820602690966;1208.9214201077077;1209.044854564884;14981.775273371126;18050.27798888082;18226.635467290846;18459.723104153716;18468.261269549068;59753.7012314953;59911.40088599058;60231.39768602258;61039;61505;65252.67373663101;76094;85483.29033419421;88058.55970720362;90508;8951.865722014045;11335.263207891461;11522.366269855238;11670.533178673099;11786.941065294619;12566.874331256571;12787.61637150887;12862.163959342613;12890.935545322214;13016.739665206833;9357.39176953496;10417.218708597036;11075.889241107487;11198.328100314007;11386.943065284951;10101.14140298083;10181.541830617492;10209.38743675382;10451.84322235152;10498.947505262426;11301.943490282825;11420;11421.029212517029;11509.769804604028;11545.538178472767;11277.379744113825;11282.153838462313;11383.943080284545;11570.76858462843;11623.012043976356;10629.521671524955;10793.784124317626;10871.075958543295;11038.503267352828;11040.447977601249 08:01:34 INFO - PROCESS | 1661 | |3;mootools.html;1604.5667669729166;1617.7543291665418;1630.8043034835819;1631.4534630898656;1636;10776.86842881498;11425.600103996361;11433.085353171757;11446.771064578703;11809.527618895234;9523.523823808817;11092.833607495893;11275.492602832872;11294.943525282382;11339.829902551468;2383.237364043511;2427.6965379327585;2436.0012394918085;2437.500312435955;2438.3782135555407;2350.565145448089;2407.6990376202975;2433.6471211674298;2439.841410308324;2452.534018536481;2166.7183266175375;2173.7608863025134;2213.5904857601313;2417.3352328109736;2418.733939266679;2219.1678120704737;2230.542738738555;2234.162189179058;2244.551089782042;2306.4810417656063;2370.5258948210426;2372.5729368713623;2379.8999766579104;2403.6034054381007;2407.2778166549965;11154.944225278903;11332.830007549894;11351.659450216506;11361.318320900773;11367.886321136848;5830.650160990354;5917.822465326047;6006.309274433453;6015.037593984942;6018.759249630009;3013.8493075346364;3247.9931221321467;3349;3463.79217246966;3500.002499287716;1123.8258069999163;1134.1153899958047;1156.6587856582328;1174.864890537582;1178.6522975722182;13590.048696591242;14005.859941400658;14766.778498322534;15799.526014219591;15978.76031859523;3710.4805327254207;4589.954100458953;4609.677322587454;4763.2616944372985;4768.713877167381;11988.46051927648;12010.819837702442;12020.879791202151;12043.759124817454;12047.879521204852;4889.584385327287;5784.942150578525;5841.386654401312;5843.18195452637;5864.0031194697;5268.552173065255;5299.5760339172775;5325.8934821303355;5345.839624811262;5346.492083252085;3346.5147553604593;3624.3294990426857;3926.6269704378037;3937.1928754605237;4018.83924643011;1024.2778840917285;1030.2067408095725;1036.3729943384294;1043.8695163104612;1048.5438834107188;941.4022095968973;962.2734835199379;962.6341990043878;967.9612815487443;1019.0421004255976;5779.739911703983;6871.553349032297;6877.862442751117;6919.100516932766;6968.79093627192;977.154761131616;989.6635144051057;1018.2719355660738;1030.6392762533053;1049.6011515624166;4237.173751118564;4316.568343165658;4327.545607711208;4348.652107831366;4464.15181115587;13487.25820079905;14063.789043164361;14070.788938165935;14072.859271407155;14092.154470731584;11234.606788762354;14033.017688761893;14050.086744361788;14051.508197213048;14053.43786248559;8765.34259930495;10013.699589012342;10351.585936562453;10604.363738175734;10654.73363165927;5988.516803347819;6928.307169283032;7090.574565526085;7230.385417239383;7244.384227340735;12694.174878632859;12707.682307942006;12720.491180352683;12888.613341599766;12999.41502632365;11392.373419462008;11967.4442322501;12903.290319032169;13122.868771312165;13265.734685306434;9237.491937943478;9516.904830951602;9517.238620910171;9518.762030949005;9518.809623807347;10386.688399348031;10438.321509101885;10445.9477702611;10551.208659350426;10557.313774604627;8777.956110219407;8825;8882.289416846766;8953;9087.000429952832;8564.272036876935;8568.400211985101;8634.611442484978;8650.697225597074;8790.384673072824;7860.646270917708;7999.520028798291;8079.6768129276525;8094.51432914027;8097.149799270992;5112.412072611697;5916.49709774674;5959.374265702212;5985.521158307412;6175.69121543912;6008.399160083956;6112.755489780359;6150.169727086786;6183.165272688129;6272.686365681789 08:01:34 INFO - PROCESS | 1661 | |4;prototype.html;379.64882483702604;382.779901556605;385.5623866911056;394.5797725422425;395.70717668924993;4736.934189807292;4803.783829727662;5044.672096313741;5184.507471790183;5238.319018527586;4276.337167739005;4715.90568188636;4739.255987769667;5034.345535080443;5205.661631993918;4166.7708276044805;4331.285337919239;4342.630876375511;4370.465130224478;4385.539518350583;3932.8230229639594;4183.393407955845;4357.978210108945;4391.728162518928;4405.827803981128;3758.5677647070524;4249.936250956238;4368.679803349445;4386.670999675021;4435.46774387074;3723.385641369198;3995.06116062725;4013.6588389986887;4324.416203812477;4431.68198162205;3090.4746193146993;3659.6340365963456;3822.4839646647624;3954.268460334847;4029.2747305485;4179.686523510719;4693.155232058228;5174.120399532088;5193.844184674428;5250.138674422449;4195.8810800545525;4709.599684026862;5022.522860328263;5058.291839142523;5112.568480825337;4781.9437641814;5155.690658560498;5610.915836262459;5628.455970868732;5635.690037047961;3977.886191866282;4189.031155356977;4311.245532031913;4434.645228381722;4444.066745983348;4967.448274483725;5664.0937450007805;5951.345352011275;5966.612170208966;5983.431574000463;4309.439772829513;4595.885892489492;4611.331356953223;4633.907321853544;4670.509596492388;4694.488448680008;4721.622270218374;4742.273192914088;4742.73914934675;4758.571728544448;4734.597559207437;4775.06886157196;4885.438174609894;4885.438174609894;5097.8215762448135;4869.3931002769;5256.922330922228;5503.3120859892515;5531.128210252839;5672.858178545487;1482.1551715522264;1513.9243037848064;1521.2621878388973;1573.7718030885458;1606.6304749907588;1104.8839871813343;1174.3541052421135;1178.5580407347245;1183.9340351087235;1207.7524107557929;4588.816447342069;5213.826888949956;5326.760295786621;5329.147336426231;5365.463453654604;5186.870328241824;5550.944490555124;5780.88438231233;5870.236869206976;5953.702314884238;4624.404580419749;4733.455652599926;4936.925946110811;5280.577553795687;5330.7068111253475;2854.700256473082;2981.6571094323995;2996.587746863752;3034.8452598655203;3046.1014000869795;5858.631287009986;5859.589828711949;5871.559633027455;6093.908591371133;6105.931461994133;5229.555487783505;5602.6918519481005;5841.883162336814;6029.366916473795;6073.35808923111;5209.817656382009;5236.607254455931;5245.190409308782;5901.793437229677;6068.21113255274;4840.782164802663;5748.7686205552745;5775.852160572582;5910.320313164041;5927.547750801082;5149.407818100966;5437.700926449083;5546.389897111395;5649.971750141223;5651.146171183526;5396.716040877234;5496.615236933536;5685.232493613475;6013.939860601338;6074.66589337573;3918.647321741057;4412.25715842247;4413.426254586883;4456.083883929941;4536.705114167568;5464.453554644504;5697.401772813795;5807.419258074159;5843.545710801389;5845.853853653693;5583.41374155724;5600.663960162403;5604.411536788579;5614.361250212393;5670.829875103754;4533.0645601736505;5299.435592627802;5505.03661859181;5569.721513924369;5599.692016938944;4603;4936.481669424658;5321.175217841241;5482.588805839658;5525.950069486785;4879.048585525788;5290.550303224269;5305;5581.553475722014;5659.519395571776;4358.956410435855;4664.883377915579;5210.609204309768;5431.8098866539485;5458.41705905283 08:01:34 INFO - PROCESS | 1661 | |5;yui.html;741.2180149941815;764.9694012239511;779.0456690554072;840.5083026429538;862.1335557764446;15032.473863414783;15680.215989200537;16181.75727364089;17098;17115.657686846287;15291.847081529182;15564.573409692613;15726.528204153865;15885.523434296962;15910.76133857993;924.0020777560219;932.3939439364433;962.5716556132523;968.9806203875918;969.7187815533487;961.1974001708581;961.6105477281712;965.6716716316452;966.0194902174298;969.2633598465146;1028.6451174344838;1033.2508931025022;1034.7826956339177;1040.9375437473775;1045.6915210013065;1016.3292227130058;1022.6574097677287;1025.4770067265674;1026.4457193115709;1028.963986260481;760.6995236881456;764.4228607401376;765.6860687118254;765.9923400765977;767.7811823630238;16835;17178.82821171785;17365.565860853454;17412.651746964988;17512.649747004987;14168.574942751631;14245.572632821031;14340.64148396288;14372.209528475929;14505.05717128394;11773.529058837636;11920.225185362924;11982.760344793143;12032.879671203264;12547.24716517012;3650;3699;3738.906527336811;3835.463035175078;3859.2281543691092;93574;111113.88886111116;119736.40131799372;120185;120253.79746202513;10643.73390665224;10763.569457221623;10779.784404312026;11136.33182009082;11205.663830084948;23215.767842321697;23396;23499.53000940006;23530.294091177293;23581.646275305884;7851.882221766678;7908.169642187488;7920.089189743138;7922.7227047053075;7929.207079292025;4339.956600434019;4341.978290108593;4537.977310113428;4540.500544940054;4637.675362724644;3433.759636825398;3498.1429549759964;3507.5089487471773;3510.4734289856215;3518.0149558123767;546.6337553838938;589.2369381650773;618.7061145955635;632.2981490545491;648.063548172895;617.3456136495329;670.852412469256;686.3582550315471;689.4105539763531;700.7965853537464;9780.853287200556;10376.010005757298;10419.843702344471;10635.52140153695;10831.458427078614;653.6013032050446;667.0328024364691;686.588047171703;706.4419108903909;710.8045287545966;4174.853880114181;4973.35346404965;5042.470540593258;5046.44489106198;5075.934053848733;98997.50501247447;106226;107603;107724;108600;75268;85403;86474;89078.55460722654;91095.54452227829;69754;69992;71421;71476.14095436124;76345.61827190829;10304.433256170983;10644.987959959599;10956.28784129029;11048.171387145836;11388.601398951329;41363.79318103511;43058.708238752886;43930.780346098065;44452.55547444613;46644;62963;64537.35462645313;64735;64933;64940.70118597696;10431.426271554834;12620.621381358575;12877.549285775327;13042.152260103063;13374.264415457243;14328.856711432752;14646.63383415423;14731;14777.482788102365;15269.160196189316;13550.457981681016;13686.384112714753;13816.585502434944;13854.722905541632;13908.582742517741;13320.733585328046;13601.319934003062;13721.931390342985;14069.085509442262;14089.365978530786;11080.889191108308;12996.285204313854;13277.734445311233;13307.600771976857;13772.10481318711;9395.483248421402;9610.135087842127;9704;9910.207183425164;10118.949405252926;10927.344359338465;11361.544851737599;11502.48238829238;11503.827442588201;11539.538418463504 08:01:34 INFO - PROCESS | 1661 | __end_tp_report 08:01:34 INFO - PROCESS | 1661 | __start_cc_report 08:01:34 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,381 08:01:34 INFO - PROCESS | 1661 | __end_cc_report 08:01:34 INFO - PROCESS | 1661 | __startTimestamp1497625294226__endTimestamp 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | ------- Summary: start ------- 08:01:34 INFO - PROCESS | 1661 | Number of tests: 6 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5930.45 Median:6210.38 stddev:692.78 (11.2%) stddev-sans-first:654.84 08:01:34 INFO - PROCESS | 1661 | Values: 2970.6 3237.7 3365.5 3497.8 3527.0 5421.6 5655.5 5825.5 6035.2 6079.0 5594.2 6101.7 6108.2 6115.7 6163.4 5447.3 5520.8 5541.0 5559.0 5570.5 5555.8 5559.4 5563.3 5570.9 5587.6 5081.0 5178.0 5364.7 5365.8 5580.9 5416.5 5475.7 5481.3 5485.6 5508.9 5468.9 5474.7 5497.7 5505.2 5554.5 6161.2 6191.5 6192.2 6209.0 6210.4 5368.2 6314.7 6322.5 6392.2 6411.8 6382.5 6397.6 6435.7 6483.9 6522.4 6210.3 6211.4 6223.0 6229.5 6266.9 5801.8 6080.1 6493.3 6661.1 6663.4 6252.1 6269.5 6277.7 6289.2 6289.5 5446.2 6468.8 6500.0 6500.2 6517.9 6341.9 6352.8 6392.9 6394.4 6421.0 6296.3 6299.2 6302.3 6304.3 6418.8 4253.9 5044.8 5165.8 5173.0 5212.7 4329.2 4567.5 4586.1 4599.7 4609.1 4879.5 5364.9 5993.5 6134.4 6158.1 5758.2 6252.8 6310.6 6347.7 6366.8 5063.8 6137.5 6156.8 6161.4 6175.4 5676.8 5705.7 5714.7 5716.9 5731.2 6350.6 6436.1 6544.7 6676.6 6681.8 6454.4 6455.1 6456.9 6466.6 6496.5 6647.8 6660.7 6708.6 6711.7 6714.4 6269.3 6303.1 6350.9 6367.9 6379.6 6327.4 6340.8 6341.0 6472.8 6482.4 6304.8 6336.1 6346.2 6346.9 6352.4 6174.3 6232.8 6285.9 6333.0 6359.0 6046.1 6146.8 6249.3 6318.4 6375.3 5280.7 6223.2 6309.4 6319.4 6319.8 5919.7 6070.2 6111.7 6216.6 6314.2 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:58000.16 Median:29429.41 stddev:120310.11 (408.8%) stddev-sans-first:120570.70 08:01:34 INFO - PROCESS | 1661 | Values: 704.2 768.8 838.2 971.7 977.8 33706.5 34683.8 43107.7 43236.6 43249.8 41444.4 41757.0 41785.2 41856.2 41874.6 8053.3 11011.8 11278.9 11431.8 11456.7 11086.7 11181.9 11201.0 11223.0 11236.9 11183.1 11234.7 11243.6 11260.2 11260.9 11176.2 11179.2 11179.6 11195.8 11204.3 10952.3 11002.8 11011.2 11019.7 11049.6 35376.5 35575.0 35756.5 35841.6 35980.0 3855.4 3869.6 3891.7 3911.8 3932.0 26160.6 27222.0 27507.0 27903.3 27926.4 3005.9 3104.3 3128.8 3136.0 3185.9 375807.1 376005.1 376394.1 376395.0 378349.0 46559.3 46688.3 46694.5 46861.5 47154.5 40193.0 47845.8 48492.5 48630.8 48645.5 14213.5 14343.8 14357.6 14388.4 14404.6 12137.5 12269.1 12318.9 12330.8 12346.4 11234.2 12260.3 12263.0 12274.8 12395.8 4456.0 4644.7 5135.1 5723.5 5803.5 1056.8 1088.0 1131.4 1135.0 1211.6 24349.5 29075.8 29379.3 29590.3 29611.8 1019.7 1151.4 1170.3 1183.6 1198.7 13110.0 14398.6 14488.1 14845.6 15018.8 497905.0 501047.0 502215.0 502342.0 502587.0 59870.0 60012.0 60219.0 60286.0 60378.0 1352.4 1392.8 1393.1 1404.7 1406.7 24300.8 27404.6 27892.0 28136.9 28472.7 48934.5 49007.0 49025.0 49122.8 49284.3 405262.0 484195.0 484377.0 484935.0 485467.6 31411.4 32111.2 33640.0 33979.3 34053.8 40575.8 40959.8 41537.4 41656.8 41793.6 28357.9 28579.7 28736.7 28753.0 29209.3 23493.5 28195.7 28302.4 28688.7 28794.3 29479.6 29986.8 32310.5 33150.7 33358.0 22384.8 26853.5 26859.6 29246.6 30545.8 23265.8 24175.9 24210.3 24336.6 24626.0 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:44339.59 Median:11924.19 stddev:132419.90 (1110.5%) stddev-sans-first:132750.29 08:01:34 INFO - PROCESS | 1661 | Values: 408.3 418.5 420.7 431.2 459.4 19700.6 20674.0 22098.8 22232.4 22295.9 19993.3 21283.6 22488.2 22601.8 22768.3 6746.2 6895.9 6908.7 6915.1 6935.0 6802.7 7523.0 7885.6 8006.7 8026.0 7766.3 7941.3 7954.4 7979.4 8016.3 6908.7 7762.3 7950.3 8019.4 8049.3 7517.0 7750.5 7785.7 7788.5 7799.1 18857.0 25792.5 26330.2 26485.6 26735.0 2877.4 2962.3 2981.2 2987.4 2989.7 13633.0 15741.9 16458.3 16868.3 17041.7 2047.8 2124.3 2164.1 2171.8 2243.8 131891.0 141358.0 142686.0 162589.0 177864.2 16773.2 19887.8 20086.8 20479.0 20509.4 32359.4 32649.3 32878.8 32886.7 32969.5 10226.8 10384.1 10522.5 10584.4 10745.2 8672.3 8725.8 8733.9 8863.0 8863.3 9527.9 9604.7 9624.4 9696.2 9747.5 3545.9 4185.3 4251.1 4265.9 4289.8 988.1 1023.9 1037.4 1056.3 1059.9 21294.7 21569.5 21635.9 21684.2 21716.7 849.0 1017.8 1051.6 1057.7 1066.7 8871.4 10632.8 11055.6 11101.6 11131.7 755655.0 791285.0 795437.0 815769.0 816734.0 158560.0 162237.9 163695.0 167167.0 168394.5 1168.4 1175.2 1196.0 1208.9 1209.0 14981.8 18050.3 18226.6 18459.7 18468.3 59753.7 59911.4 60231.4 61039.0 61505.0 65252.7 76094.0 85483.3 88058.6 90508.0 8951.9 11335.3 11522.4 11670.5 11786.9 12566.9 12787.6 12862.2 12890.9 13016.7 9357.4 10417.2 11075.9 11198.3 11386.9 10101.1 10181.5 10209.4 10451.8 10498.9 11301.9 11420.0 11421.0 11509.8 11545.5 11277.4 11282.2 11383.9 11570.8 11623.0 10629.5 10793.8 10871.1 11038.5 11040.4 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:6798.61 Median:2430.67 stddev:4283.80 (176.2%) stddev-sans-first:4278.04 08:01:34 INFO - PROCESS | 1661 | Values: 1604.6 1617.8 1630.8 1631.5 1636.0 10776.9 11425.6 11433.1 11446.8 11809.5 9523.5 11092.8 11275.5 11294.9 11339.8 2383.2 2427.7 2436.0 2437.5 2438.4 2350.6 2407.7 2433.6 2439.8 2452.5 2166.7 2173.8 2213.6 2417.3 2418.7 2219.2 2230.5 2234.2 2244.6 2306.5 2370.5 2372.6 2379.9 2403.6 2407.3 11154.9 11332.8 11351.7 11361.3 11367.9 5830.7 5917.8 6006.3 6015.0 6018.8 3013.8 3248.0 3349.0 3463.8 3500.0 1123.8 1134.1 1156.7 1174.9 1178.7 13590.0 14005.9 14766.8 15799.5 15978.8 3710.5 4590.0 4609.7 4763.3 4768.7 11988.5 12010.8 12020.9 12043.8 12047.9 4889.6 5784.9 5841.4 5843.2 5864.0 5268.6 5299.6 5325.9 5345.8 5346.5 3346.5 3624.3 3926.6 3937.2 4018.8 1024.3 1030.2 1036.4 1043.9 1048.5 941.4 962.3 962.6 968.0 1019.0 5779.7 6871.6 6877.9 6919.1 6968.8 977.2 989.7 1018.3 1030.6 1049.6 4237.2 4316.6 4327.5 4348.7 4464.2 13487.3 14063.8 14070.8 14072.9 14092.2 11234.6 14033.0 14050.1 14051.5 14053.4 8765.3 10013.7 10351.6 10604.4 10654.7 5988.5 6928.3 7090.6 7230.4 7244.4 12694.2 12707.7 12720.5 12888.6 12999.4 11392.4 11967.4 12903.3 13122.9 13265.7 9237.5 9516.9 9517.2 9518.8 9518.8 10386.7 10438.3 10445.9 10551.2 10557.3 8778.0 8825.0 8882.3 8953.0 9087.0 8564.3 8568.4 8634.6 8650.7 8790.4 7860.6 7999.5 8079.7 8094.5 8097.1 5112.4 5916.5 5959.4 5985.5 6175.7 6008.4 6112.8 6150.2 6183.2 6272.7 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4654.27 Median:4952.19 stddev:1304.62 (26.3%) stddev-sans-first:1268.21 08:01:34 INFO - PROCESS | 1661 | Values: 379.6 382.8 385.6 394.6 395.7 4736.9 4803.8 5044.7 5184.5 5238.3 4276.3 4715.9 4739.3 5034.3 5205.7 4166.8 4331.3 4342.6 4370.5 4385.5 3932.8 4183.4 4358.0 4391.7 4405.8 3758.6 4249.9 4368.7 4386.7 4435.5 3723.4 3995.1 4013.7 4324.4 4431.7 3090.5 3659.6 3822.5 3954.3 4029.3 4179.7 4693.2 5174.1 5193.8 5250.1 4195.9 4709.6 5022.5 5058.3 5112.6 4781.9 5155.7 5610.9 5628.5 5635.7 3977.9 4189.0 4311.2 4434.6 4444.1 4967.4 5664.1 5951.3 5966.6 5983.4 4309.4 4595.9 4611.3 4633.9 4670.5 4694.5 4721.6 4742.3 4742.7 4758.6 4734.6 4775.1 4885.4 4885.4 5097.8 4869.4 5256.9 5503.3 5531.1 5672.9 1482.2 1513.9 1521.3 1573.8 1606.6 1104.9 1174.4 1178.6 1183.9 1207.8 4588.8 5213.8 5326.8 5329.1 5365.5 5186.9 5550.9 5780.9 5870.2 5953.7 4624.4 4733.5 4936.9 5280.6 5330.7 2854.7 2981.7 2996.6 3034.8 3046.1 5858.6 5859.6 5871.6 6093.9 6105.9 5229.6 5602.7 5841.9 6029.4 6073.4 5209.8 5236.6 5245.2 5901.8 6068.2 4840.8 5748.8 5775.9 5910.3 5927.5 5149.4 5437.7 5546.4 5650.0 5651.1 5396.7 5496.6 5685.2 6013.9 6074.7 3918.6 4412.3 4413.4 4456.1 4536.7 5464.5 5697.4 5807.4 5843.5 5845.9 5583.4 5600.7 5604.4 5614.4 5670.8 4533.1 5299.4 5505.0 5569.7 5599.7 4603.0 4936.5 5321.2 5482.6 5526.0 4879.0 5290.6 5305.0 5581.6 5659.5 4359.0 4664.9 5210.6 5431.8 5458.4 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:20452.45 Median:23514.91 stddev:29395.23 (125.0%) stddev-sans-first:29440.43 08:01:34 INFO - PROCESS | 1661 | Values: 741.2 765.0 779.0 840.5 862.1 15032.5 15680.2 16181.8 17098.0 17115.7 15291.8 15564.6 15726.5 15885.5 15910.8 924.0 932.4 962.6 969.0 969.7 961.2 961.6 965.7 966.0 969.3 1028.6 1033.3 1034.8 1040.9 1045.7 1016.3 1022.7 1025.5 1026.4 1029.0 760.7 764.4 765.7 766.0 767.8 16835.0 17178.8 17365.6 17412.7 17512.6 14168.6 14245.6 14340.6 14372.2 14505.1 11773.5 11920.2 11982.8 12032.9 12547.2 3650.0 3699.0 3738.9 3835.5 3859.2 93574.0 111113.9 119736.4 120185.0 120253.8 10643.7 10763.6 10779.8 11136.3 11205.7 23215.8 23396.0 23499.5 23530.3 23581.6 7851.9 7908.2 7920.1 7922.7 7929.2 4340.0 4342.0 4538.0 4540.5 4637.7 3433.8 3498.1 3507.5 3510.5 3518.0 546.6 589.2 618.7 632.3 648.1 617.3 670.9 686.4 689.4 700.8 9780.9 10376.0 10419.8 10635.5 10831.5 653.6 667.0 686.6 706.4 710.8 4174.9 4973.4 5042.5 5046.4 5075.9 98997.5 106226.0 107603.0 107724.0 108600.0 75268.0 85403.0 86474.0 89078.6 91095.5 69754.0 69992.0 71421.0 71476.1 76345.6 10304.4 10645.0 10956.3 11048.2 11388.6 41363.8 43058.7 43930.8 44452.6 46644.0 62963.0 64537.4 64735.0 64933.0 64940.7 10431.4 12620.6 12877.5 13042.2 13374.3 14328.9 14646.6 14731.0 14777.5 15269.2 13550.5 13686.4 13816.6 13854.7 13908.6 13320.7 13601.3 13721.9 14069.1 14089.4 11080.9 12996.3 13277.7 13307.6 13772.1 9395.5 9610.1 9704.0 9910.2 10118.9 10927.3 11361.5 11502.5 11503.8 11539.5 08:01:34 INFO - PROCESS | 1661 | -------- Summary: end -------- 08:01:34 INFO - PROCESS | 1661 | 08:01:34 INFO - TEST-INFO | 1661: exit 0 08:01:34 INFO - TEST-OK | dromaeo_css | took 1116636ms 08:01:34 INFO - TEST-START | kraken 08:01:34 INFO - operating with platform_type : mac_ 08:01:34 INFO - Initialising browser for kraken test... 08:01:34 INFO - TEST-INFO | started process 1785 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmprZ75J9/profile http://localhost:49230/getInfo.html) 08:01:37 INFO - PROCESS | 1785 | 2017-06-16 08:01:37.446 plugin-container[1787:10477] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:01:37 INFO - PROCESS | 1785 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:01:37 INFO - PROCESS | 1785 | 2017-06-16 08:01:37.446 plugin-container[1787:10477] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 08:01:37 INFO - PROCESS | 1785 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:01:37 INFO - PROCESS | 1785 | 2017-06-16 08:01:37.446 plugin-container[1787:10477] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:01:37 INFO - PROCESS | 1785 | __metrics Screen width/height:1600/1200 08:01:37 INFO - PROCESS | 1785 | colorDepth:24 08:01:37 INFO - PROCESS | 1785 | Browser inner width/height: 1024/689 08:01:37 INFO - PROCESS | 1785 | __metrics 08:01:37 ERROR - PROCESS | 1785 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:01:38 INFO - TEST-INFO | 1785: exit 0 08:01:38 INFO - Browser initialized. 08:01:38 INFO - Running cycle 1/1 for kraken test... 08:01:38 INFO - TEST-INFO | started process 1789 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmprZ75J9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:40 INFO - PROCESS | 1789 | 2017-06-16 08:01:40.537 plugin-container[1790:10606] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 08:01:40 INFO - PROCESS | 1789 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:01:40 INFO - PROCESS | 1789 | 2017-06-16 08:01:40.538 plugin-container[1790:10606] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8613, name = 'com.apple.CFPasteboardClient' 08:01:40 INFO - PROCESS | 1789 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:01:40 INFO - PROCESS | 1789 | 2017-06-16 08:01:40.538 plugin-container[1790:10606] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:01:50 INFO - PROCESS | 1789 | RSS: Main: 202854400 08:01:50 INFO - PROCESS | 1789 | 08:01:53 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 08:01:54 INFO - PROCESS | 1789 | RSS: Main: 216375296 08:01:54 INFO - PROCESS | 1789 | 08:01:57 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 08:01:57 INFO - PROCESS | 1789 | RSS: Main: 224206848 08:01:57 INFO - PROCESS | 1789 | 08:02:01 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 08:02:01 INFO - PROCESS | 1789 | RSS: Main: 227713024 08:02:01 INFO - PROCESS | 1789 | 08:02:03 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 08:02:04 INFO - PROCESS | 1789 | RSS: Main: 229281792 08:02:04 INFO - PROCESS | 1789 | 08:02:06 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:02:06 INFO - PROCESS | 1789 | RSS: Main: 229302272 08:02:06 INFO - PROCESS | 1789 | 08:02:09 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 08:02:10 INFO - PROCESS | 1789 | RSS: Main: 233414656 08:02:10 INFO - PROCESS | 1789 | 08:02:13 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 08:02:14 INFO - PROCESS | 1789 | RSS: Main: 232927232 08:02:14 INFO - PROCESS | 1789 | 08:02:17 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 08:02:18 INFO - PROCESS | 1789 | RSS: Main: 233013248 08:02:18 INFO - PROCESS | 1789 | 08:02:19 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:02:20 INFO - PROCESS | 1789 | RSS: Main: 232427520 08:02:20 INFO - PROCESS | 1789 | 08:02:22 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:02:23 INFO - PROCESS | 1789 | RSS: Main: 232386560 08:02:23 INFO - PROCESS | 1789 | 08:02:25 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:02:25 INFO - PROCESS | 1789 | RSS: Main: 232476672 08:02:25 INFO - PROCESS | 1789 | 08:02:28 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:02:29 INFO - PROCESS | 1789 | RSS: Main: 232886272 08:02:29 INFO - PROCESS | 1789 | 08:02:32 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:02:33 INFO - PROCESS | 1789 | RSS: Main: 232587264 08:02:33 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 08:02:35 INFO - PROCESS | 1789 | RSS: Main: 234643456 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | __start_tp_report 08:02:35 INFO - PROCESS | 1789 | _x_x_mozilla_page_load 08:02:35 INFO - PROCESS | 1789 | _x_x_mozilla_page_load_details 08:02:35 INFO - PROCESS | 1789 | |i|pagename|runs| 08:02:35 INFO - PROCESS | 1789 | |0;ai-astar;108;121;126;169;172;121;120;124;162;117 08:02:35 INFO - PROCESS | 1789 | |1;audio-beat-detection;157;144;143;164;109;113;113;112;161;140 08:02:35 INFO - PROCESS | 1789 | |2;audio-dft;281;256;199;201;197;204;198;198;199;198 08:02:35 INFO - PROCESS | 1789 | |3;audio-fft;85;59;61;99;65;62;61;62;75;62 08:02:35 INFO - PROCESS | 1789 | |4;audio-oscillator;92;71;88;74;71;71;81;71;71;86 08:02:35 INFO - PROCESS | 1789 | |5;imaging-gaussian-blur;136;77;136;120;121;90;89;87;89;80 08:02:35 INFO - PROCESS | 1789 | |6;imaging-darkroom;156;103;99;99;97;96;95;120;96;95 08:02:35 INFO - PROCESS | 1789 | |7;imaging-desaturate;90;93;92;91;97;124;134;93;94;88 08:02:35 INFO - PROCESS | 1789 | |8;json-parse-financial;68;67;68;59;82;105;106;106;104;107 08:02:35 INFO - PROCESS | 1789 | |9;json-stringify-tinderbox;58;69;50;60;89;84;84;84;60;60 08:02:35 INFO - PROCESS | 1789 | |10;stanford-crypto-aes;89;86;91;87;84;81;84;85;91;93 08:02:35 INFO - PROCESS | 1789 | |11;stanford-crypto-ccm;138;141;149;150;138;142;142;150;206;183 08:02:35 INFO - PROCESS | 1789 | |12;stanford-crypto-pbkdf2;168;171;168;166;174;168;171;171;170;163 08:02:35 INFO - PROCESS | 1789 | |13;stanford-crypto-sha256-iterative;83;65;68;82;85;88;89;91;64;67 08:02:35 INFO - PROCESS | 1789 | __end_tp_report 08:02:35 INFO - PROCESS | 1789 | __start_cc_report 08:02:35 INFO - PROCESS | 1789 | _x_x_mozilla_cycle_collect,1007 08:02:35 INFO - PROCESS | 1789 | __end_cc_report 08:02:35 INFO - PROCESS | 1789 | __startTimestamp1497625355280__endTimestamp 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | ------- Summary: start ------- 08:02:35 INFO - PROCESS | 1789 | Number of tests: 14 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#0] ai-astar Cycles:10 Average:134.00 Median:125.00 stddev:23.84 (19.1%) stddev-sans-first:23.36 08:02:35 INFO - PROCESS | 1789 | Values: 108.0 121.0 126.0 169.0 172.0 121.0 120.0 124.0 162.0 117.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:143.50 stddev:21.96 (15.3%) stddev-sans-first:21.88 08:02:35 INFO - PROCESS | 1789 | Values: 157.0 144.0 143.0 164.0 109.0 113.0 113.0 112.0 161.0 140.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#2] audio-dft Cycles:10 Average:213.10 Median:200.00 stddev:29.85 (14.9%) stddev-sans-first:19.03 08:02:35 INFO - PROCESS | 1789 | Values: 281.0 256.0 199.0 201.0 197.0 204.0 198.0 198.0 199.0 198.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#3] audio-fft Cycles:10 Average:69.10 Median:63.50 stddev:13.26 (20.9%) stddev-sans-first:12.76 08:02:35 INFO - PROCESS | 1789 | Values: 85.0 59.0 61.0 99.0 65.0 62.0 61.0 62.0 75.0 62.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:77.50 stddev:8.36 (10.8%) stddev-sans-first:7.05 08:02:35 INFO - PROCESS | 1789 | Values: 92.0 71.0 88.0 74.0 71.0 71.0 81.0 71.0 71.0 86.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:83.50 stddev:23.12 (27.7%) stddev-sans-first:21.11 08:02:35 INFO - PROCESS | 1789 | Values: 136.0 77.0 136.0 120.0 121.0 90.0 89.0 87.0 89.0 80.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#6] imaging-darkroom Cycles:10 Average:105.60 Median:96.00 stddev:19.22 (20.0%) stddev-sans-first:7.92 08:02:35 INFO - PROCESS | 1789 | Values: 156.0 103.0 99.0 99.0 97.0 96.0 95.0 120.0 96.0 95.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:92.50 stddev:15.85 (17.1%) stddev-sans-first:16.43 08:02:35 INFO - PROCESS | 1789 | Values: 90.0 93.0 92.0 91.0 97.0 124.0 134.0 93.0 94.0 88.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#8] json-parse-financial Cycles:10 Average:87.20 Median:63.00 stddev:20.18 (32.0%) stddev-sans-first:20.17 08:02:35 INFO - PROCESS | 1789 | Values: 68.0 67.0 68.0 59.0 82.0 105.0 106.0 106.0 104.0 107.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:76.50 stddev:14.12 (18.5%) stddev-sans-first:14.31 08:02:35 INFO - PROCESS | 1789 | Values: 58.0 69.0 50.0 60.0 89.0 84.0 84.0 84.0 60.0 60.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:88.00 stddev:3.81 (4.3%) stddev-sans-first:3.98 08:02:35 INFO - PROCESS | 1789 | Values: 89.0 86.0 91.0 87.0 84.0 81.0 84.0 85.0 91.0 93.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#11] stanford-crypto-ccm Cycles:10 Average:153.90 Median:149.50 stddev:22.54 (15.1%) stddev-sans-first:23.16 08:02:35 INFO - PROCESS | 1789 | Values: 138.0 141.0 149.0 150.0 138.0 142.0 142.0 150.0 206.0 183.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:170.50 stddev:3.09 (1.8%) stddev-sans-first:3.26 08:02:35 INFO - PROCESS | 1789 | Values: 168.0 171.0 168.0 166.0 174.0 168.0 171.0 171.0 170.0 163.0 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - PROCESS | 1789 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.20 Median:84.00 stddev:10.88 (13.0%) stddev-sans-first:11.40 08:02:35 INFO - PROCESS | 1789 | Values: 83.0 65.0 68.0 82.0 85.0 88.0 89.0 91.0 64.0 67.0 08:02:35 INFO - PROCESS | 1789 | -------- Summary: end -------- 08:02:35 INFO - PROCESS | 1789 | 08:02:35 INFO - TEST-INFO | 1789: exit 0 08:02:35 INFO - TEST-OK | kraken | took 61216ms 08:02:35 INFO - SUITE-END | took 1177s 08:02:35 INFO - Completed test suite (00:19:37) 08:02:35 INFO - javascript benchmark 08:02:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7776.783903797869, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2970.554416837475, 3237.7409807215427, 3365.4615261558156, 3497.807620580867, 3526.999999999999, 5421.590386499509, 5655.462731040549, 5825.475707186352, 6035.245594300718, 6079.027355623096, 5594.216809646653, 6101.69491525423, 6108.175396321495, 6115.724792384342, 6163.383661633834, 5447.291852059237, 5520.751566179531, 5541.0, 5558.972205138969, 5570.498655121039, 5555.805546805863, 5559.444055594448, 5563.304586926634, 5570.888582228353, 5587.580931430139, 5080.980292977078, 5177.974110129444, 5364.704941228231, 5365.839024829242, 5580.944190558083, 5416.51251387377, 5475.72621368932, 5481.260029895953, 5485.561155107602, 5508.944910550883, 5468.91796623051, 5474.698891561003, 5497.725113744297, 5505.201745746885, 5554.472325129106, 6161.19904412428, 6191.535634827406, 6192.195014648112, 6208.96895515524, 6210.378962103799, 5368.221607866876, 6314.652694101823, 6322.525810564226, 6392.20097487814, 6411.807645770635, 6382.521310901655, 6397.584157029778, 6435.742570297182, 6483.902741458882, 6522.380373864476, 6210.285817131043, 6211.378862113753, 6223.0354295084335, 6229.53278504114, 6266.937330626726, 5801.796937107265, 6080.0575910733905, 6493.285738568754, 6661.134052573231, 6663.36698013688, 6252.093446450239, 6269.49844012487, 6277.654728989949, 6289.213848268966, 6289.528285378524, 5446.2375267462585, 6468.838279043061, 6500.0, 6500.154979852589, 6517.902231466532, 6341.936580634135, 6352.777652782131, 6392.904106438407, 6394.392532709385, 6421.036844473273, 6296.338884417162, 6299.244090709144, 6302.338254483305, 6304.338044505261, 6418.775342862978, 4253.936190957138, 5044.848654540369, 5165.845024649267, 5173.042987047408, 5212.739363031909, 4329.15581461612, 4567.474740404829, 4586.128635559233, 4599.724016559017, 4609.055143695535, 4879.463259041502, 5364.873376590882, 5993.520518358521, 6134.38656134383, 6158.137860699506, 5758.165066065397, 6252.812415627538, 6310.558260921784, 6347.682615869188, 6366.808995730135, 5063.772130254148, 6137.508999280047, 6156.846078848064, 6161.4146656067605, 6175.413335733098, 5676.801311954062, 5705.657660540298, 5714.657120572779, 5716.885662286731, 5731.226284451545, 6350.555461117678, 6436.13112229843, 6544.738210471718, 6676.56602320867, 6681.76613818514, 6454.41910228063, 6455.0962865199845, 6456.870862582816, 6466.579672321284, 6496.480281577557, 6647.833804154935, 6660.700268488026, 6708.6310252936555, 6711.731530738716, 6714.362135597111, 6269.3417191196095, 6303.1175635412, 6350.90473642905, 6367.872642547216, 6379.553431259767, 6327.3988971047675, 6340.778072767615, 6341.048842673636, 6472.773452929313, 6482.416582507597, 6304.810855674337, 6336.144620476177, 6346.2067241594805, 6346.90479642815, 6352.396522330371, 6174.289956655042, 6232.75068997235, 6285.937140628535, 6333.018382150774, 6359.0, 6046.123312119806, 6146.815595532141, 6249.281332646804, 6318.368163183645, 6375.298717141018, 5280.709560974184, 6223.222097237845, 6309.3690630936535, 6319.431251187415, 6319.842003949938, 5919.674417906885, 6070.1501789750355, 6111.743676692077, 6216.564840461125, 6314.242290925118], "value": 5885.55625919461, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.1690804850276, 768.7891570776026, 838.2204549768717, 971.6744890461699, 977.8239916814979, 33706.49440258395, 34683.82658086709, 43107.70676879691, 43236.567634323685, 43249.7837510812, 41444.37833432493, 41757.0, 41785.16429671412, 41856.16287674245, 41874.58125418745, 8053.275205231514, 11011.83482247767, 11278.928501792316, 11431.828522572168, 11456.656300311004, 11086.667399978014, 11181.944090279578, 11201.0, 11223.0, 11236.887631123665, 11183.105351571854, 11234.719132021683, 11243.60647377342, 11260.21178517504, 11260.887391126107, 11176.161787865862, 11179.217454778169, 11179.552817887276, 11195.832062519068, 11204.327740335606, 10952.288101273392, 11002.834957475565, 11011.174161937886, 11019.724506887313, 11049.558017679283, 35376.46935295973, 35575.0, 35756.463653045226, 35841.64158358409, 35980.0, 3855.363864962278, 3869.5743468218475, 3891.747036442622, 3911.8239679214503, 3932.016995751062, 26160.607590886153, 27222.0, 27507.0, 27903.302417439725, 27926.441471170463, 3005.8782504732358, 3104.3480869017535, 3128.8122712637314, 3136.0, 3185.9203519911957, 375807.12096439616, 376005.11997440114, 376394.11802941083, 376395.0, 378349.0, 46559.30161047587, 46688.299675504895, 46694.53305466902, 46861.53138468572, 47154.5284547157, 40193.0, 47845.76077119592, 48492.5150748488, 48630.78423039452, 48645.51354486481, 14213.50758170398, 14343.780778633933, 14357.6410589734, 14388.352524136439, 14404.567862964128, 12137.453814578364, 12269.141160118885, 12318.876811231772, 12330.75338493243, 12346.382680865921, 11234.157438192005, 12260.26438413698, 12263.0189584833, 12274.815877761841, 12395.814062789066, 4455.9554404456185, 4644.7213167210075, 5135.12702840518, 5723.513501352431, 5803.47768700819, 1056.8374787733585, 1087.983680244797, 1131.4455916600807, 1134.9659510214706, 1211.5884993982088, 24349.51300974006, 29075.836966521525, 29379.265518361786, 29590.260243493653, 29611.815527378665, 1019.725343320849, 1151.401271338899, 1170.309517384747, 1183.5679976808576, 1198.7183011992174, 13110.016748743883, 14398.640033999234, 14488.058276212012, 14845.554633361016, 15018.774718379233, 497905.0, 501047.0, 502215.0, 502342.0, 502587.0, 59870.0, 60012.0, 60219.0, 60286.0, 60378.0, 1352.4184600621631, 1392.8398234202994, 1393.0666453476397, 1404.6769243073945, 1406.7256884907345, 24300.756992429848, 27404.588931166447, 27892.0, 28136.859315703292, 28472.715272847836, 48934.510654893, 49007.0, 49025.019499609094, 49122.75438622784, 49284.26073608827, 405262.0, 484195.0, 484377.0, 484935.0, 485467.5726621344, 31411.371772563965, 32111.197220069684, 33640.0, 33979.32041359109, 34053.82973085218, 40575.797121014206, 40959.77120686384, 41537.37693934654, 41656.791716041225, 41793.582064180184, 28357.858210708815, 28579.714202858537, 28736.71263287424, 28753.0, 29209.269768255115, 23493.530129396975, 28195.71804281931, 28302.43395132127, 28688.713112868605, 28794.28014299659, 29479.557806633347, 29986.800527978638, 32310.515342270355, 33150.668493314755, 33358.0, 22384.776152238268, 26853.462930741665, 26859.597106043817, 29246.561301580918, 30545.847270763505, 23265.76734232704, 24175.879120604284, 24210.273691789276, 24336.634950475403, 24626.0], "value": 18819.42491322714, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.29161404962394, 418.4664552695313, 420.6992000719485, 431.1764529748182, 459.3729559151757, 19700.60598788025, 20673.96630168491, 22098.779012209874, 22232.444188895286, 22295.888520557415, 19993.300234491795, 21283.57432851342, 22488.212912548024, 22601.773982260172, 22768.31695049147, 6746.224184218816, 6895.862082758342, 6908.654567271641, 6915.135608048994, 6934.965325173393, 6802.659867006654, 7523.0, 7885.566293853835, 8006.67973281068, 8026.0, 7766.301032907038, 7941.28528432441, 7954.443188976774, 7979.441439099265, 8016.318612917909, 6908.689108990107, 7762.301392874669, 7950.3242224411, 8019.398545109111, 8049.315808156313, 7517.0, 7750.534967901944, 7785.727499537546, 7788.493747906425, 7799.1031031430975, 18857.0, 25792.484150317076, 26330.21009369722, 26485.602715959278, 26735.0, 2877.4101309231764, 2962.3482833776534, 2981.1950773291305, 2987.4323878463024, 2989.7309242168094, 13633.0, 15741.92129039359, 16458.259378328003, 16868.325266989305, 17041.659166816593, 2047.7509168475474, 2124.2989813361555, 2164.134346261508, 2171.7936796004356, 2243.8317126215597, 131891.0, 141358.0, 142686.0, 162589.0, 177864.22135778735, 16773.24520396585, 19887.801121988883, 20086.799132008786, 20479.0, 20509.38471845847, 32359.35281294361, 32649.34701306008, 32878.83560582182, 32886.67113328884, 32969.505457418156, 10226.795464090677, 10384.065434110815, 10522.526486308134, 10584.417857017937, 10745.194110441747, 8672.262857657044, 8725.825483490295, 8733.912660873437, 8863.025067242597, 8863.335249856287, 9527.857082143773, 9604.66383676568, 9624.422534647943, 9696.224302055818, 9747.463889486004, 3545.8936231913085, 4185.3423076346735, 4251.128518653669, 4265.936010959838, 4289.828406863753, 988.0613417253638, 1023.892491288404, 1037.37650576847, 1056.3133962924007, 1059.8834128245887, 21294.680579791315, 21569.460763480725, 21635.891820540797, 21684.24105156312, 21716.674249886266, 848.9957550212209, 1017.801528701896, 1051.6476980211714, 1057.7038429239826, 1066.7119877632995, 8871.423357481743, 10632.840507392395, 11055.557777688802, 11101.5559377624, 11131.666050018512, 755655.0, 791285.0, 795437.0, 815769.0, 816734.0, 158560.0, 162237.9293180687, 163695.0, 167167.0, 168394.47408288886, 1168.415792103948, 1175.2184797109996, 1195.9820602690966, 1208.9214201077077, 1209.044854564884, 14981.775273371126, 18050.27798888082, 18226.635467290846, 18459.723104153716, 18468.261269549068, 59753.7012314953, 59911.40088599058, 60231.39768602258, 61039.0, 61505.0, 65252.67373663101, 76094.0, 85483.29033419421, 88058.55970720362, 90508.0, 8951.865722014045, 11335.263207891461, 11522.366269855238, 11670.533178673099, 11786.941065294619, 12566.874331256571, 12787.61637150887, 12862.163959342613, 12890.935545322214, 13016.739665206833, 9357.39176953496, 10417.218708597036, 11075.889241107487, 11198.328100314007, 11386.943065284951, 10101.14140298083, 10181.541830617492, 10209.38743675382, 10451.84322235152, 10498.947505262426, 11301.943490282825, 11420.0, 11421.029212517029, 11509.769804604028, 11545.538178472767, 11277.379744113825, 11282.153838462313, 11383.943080284545, 11570.76858462843, 11623.012043976356, 10629.521671524955, 10793.784124317626, 10871.075958543295, 11038.503267352828, 11040.447977601249], "value": 11732.977317549028, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1604.5667669729166, 1617.7543291665418, 1630.8043034835819, 1631.4534630898656, 1636.0, 10776.86842881498, 11425.600103996361, 11433.085353171757, 11446.771064578703, 11809.527618895234, 9523.523823808817, 11092.833607495893, 11275.492602832872, 11294.943525282382, 11339.829902551468, 2383.237364043511, 2427.6965379327585, 2436.0012394918085, 2437.500312435955, 2438.3782135555407, 2350.565145448089, 2407.6990376202975, 2433.6471211674298, 2439.841410308324, 2452.534018536481, 2166.7183266175375, 2173.7608863025134, 2213.5904857601313, 2417.3352328109736, 2418.733939266679, 2219.1678120704737, 2230.542738738555, 2234.162189179058, 2244.551089782042, 2306.4810417656063, 2370.5258948210426, 2372.5729368713623, 2379.8999766579104, 2403.6034054381007, 2407.2778166549965, 11154.944225278903, 11332.830007549894, 11351.659450216506, 11361.318320900773, 11367.886321136848, 5830.650160990354, 5917.822465326047, 6006.309274433453, 6015.037593984942, 6018.759249630009, 3013.8493075346364, 3247.9931221321467, 3349.0, 3463.79217246966, 3500.002499287716, 1123.8258069999163, 1134.1153899958047, 1156.6587856582328, 1174.864890537582, 1178.6522975722182, 13590.048696591242, 14005.859941400658, 14766.778498322534, 15799.526014219591, 15978.76031859523, 3710.4805327254207, 4589.954100458953, 4609.677322587454, 4763.2616944372985, 4768.713877167381, 11988.46051927648, 12010.819837702442, 12020.879791202151, 12043.759124817454, 12047.879521204852, 4889.584385327287, 5784.942150578525, 5841.386654401312, 5843.18195452637, 5864.0031194697, 5268.552173065255, 5299.5760339172775, 5325.8934821303355, 5345.839624811262, 5346.492083252085, 3346.5147553604593, 3624.3294990426857, 3926.6269704378037, 3937.1928754605237, 4018.83924643011, 1024.2778840917285, 1030.2067408095725, 1036.3729943384294, 1043.8695163104612, 1048.5438834107188, 941.4022095968973, 962.2734835199379, 962.6341990043878, 967.9612815487443, 1019.0421004255976, 5779.739911703983, 6871.553349032297, 6877.862442751117, 6919.100516932766, 6968.79093627192, 977.154761131616, 989.6635144051057, 1018.2719355660738, 1030.6392762533053, 1049.6011515624166, 4237.173751118564, 4316.568343165658, 4327.545607711208, 4348.652107831366, 4464.15181115587, 13487.25820079905, 14063.789043164361, 14070.788938165935, 14072.859271407155, 14092.154470731584, 11234.606788762354, 14033.017688761893, 14050.086744361788, 14051.508197213048, 14053.43786248559, 8765.34259930495, 10013.699589012342, 10351.585936562453, 10604.363738175734, 10654.73363165927, 5988.516803347819, 6928.307169283032, 7090.574565526085, 7230.385417239383, 7244.384227340735, 12694.174878632859, 12707.682307942006, 12720.491180352683, 12888.613341599766, 12999.41502632365, 11392.373419462008, 11967.4442322501, 12903.290319032169, 13122.868771312165, 13265.734685306434, 9237.491937943478, 9516.904830951602, 9517.238620910171, 9518.762030949005, 9518.809623807347, 10386.688399348031, 10438.321509101885, 10445.9477702611, 10551.208659350426, 10557.313774604627, 8777.956110219407, 8825.0, 8882.289416846766, 8953.0, 9087.000429952832, 8564.272036876935, 8568.400211985101, 8634.611442484978, 8650.697225597074, 8790.384673072824, 7860.646270917708, 7999.520028798291, 8079.6768129276525, 8094.51432914027, 8097.149799270992, 5112.412072611697, 5916.49709774674, 5959.374265702212, 5985.521158307412, 6175.69121543912, 6008.399160083956, 6112.755489780359, 6150.169727086786, 6183.165272688129, 6272.686365681789], "value": 5162.323769400415, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.64882483702604, 382.779901556605, 385.5623866911056, 394.5797725422425, 395.70717668924993, 4736.934189807292, 4803.783829727662, 5044.672096313741, 5184.507471790183, 5238.319018527586, 4276.337167739005, 4715.90568188636, 4739.255987769667, 5034.345535080443, 5205.661631993918, 4166.7708276044805, 4331.285337919239, 4342.630876375511, 4370.465130224478, 4385.539518350583, 3932.8230229639594, 4183.393407955845, 4357.978210108945, 4391.728162518928, 4405.827803981128, 3758.5677647070524, 4249.936250956238, 4368.679803349445, 4386.670999675021, 4435.46774387074, 3723.385641369198, 3995.06116062725, 4013.6588389986887, 4324.416203812477, 4431.68198162205, 3090.4746193146993, 3659.6340365963456, 3822.4839646647624, 3954.268460334847, 4029.2747305485, 4179.686523510719, 4693.155232058228, 5174.120399532088, 5193.844184674428, 5250.138674422449, 4195.8810800545525, 4709.599684026862, 5022.522860328263, 5058.291839142523, 5112.568480825337, 4781.9437641814, 5155.690658560498, 5610.915836262459, 5628.455970868732, 5635.690037047961, 3977.886191866282, 4189.031155356977, 4311.245532031913, 4434.645228381722, 4444.066745983348, 4967.448274483725, 5664.0937450007805, 5951.345352011275, 5966.612170208966, 5983.431574000463, 4309.439772829513, 4595.885892489492, 4611.331356953223, 4633.907321853544, 4670.509596492388, 4694.488448680008, 4721.622270218374, 4742.273192914088, 4742.73914934675, 4758.571728544448, 4734.597559207437, 4775.06886157196, 4885.438174609894, 4885.438174609894, 5097.8215762448135, 4869.3931002769, 5256.922330922228, 5503.3120859892515, 5531.128210252839, 5672.858178545487, 1482.1551715522264, 1513.9243037848064, 1521.2621878388973, 1573.7718030885458, 1606.6304749907588, 1104.8839871813343, 1174.3541052421135, 1178.5580407347245, 1183.9340351087235, 1207.7524107557929, 4588.816447342069, 5213.826888949956, 5326.760295786621, 5329.147336426231, 5365.463453654604, 5186.870328241824, 5550.944490555124, 5780.88438231233, 5870.236869206976, 5953.702314884238, 4624.404580419749, 4733.455652599926, 4936.925946110811, 5280.577553795687, 5330.7068111253475, 2854.700256473082, 2981.6571094323995, 2996.587746863752, 3034.8452598655203, 3046.1014000869795, 5858.631287009986, 5859.589828711949, 5871.559633027455, 6093.908591371133, 6105.931461994133, 5229.555487783505, 5602.6918519481005, 5841.883162336814, 6029.366916473795, 6073.35808923111, 5209.817656382009, 5236.607254455931, 5245.190409308782, 5901.793437229677, 6068.21113255274, 4840.782164802663, 5748.7686205552745, 5775.852160572582, 5910.320313164041, 5927.547750801082, 5149.407818100966, 5437.700926449083, 5546.389897111395, 5649.971750141223, 5651.146171183526, 5396.716040877234, 5496.615236933536, 5685.232493613475, 6013.939860601338, 6074.66589337573, 3918.647321741057, 4412.25715842247, 4413.426254586883, 4456.083883929941, 4536.705114167568, 5464.453554644504, 5697.401772813795, 5807.419258074159, 5843.545710801389, 5845.853853653693, 5583.41374155724, 5600.663960162403, 5604.411536788579, 5614.361250212393, 5670.829875103754, 4533.0645601736505, 5299.435592627802, 5505.03661859181, 5569.721513924369, 5599.692016938944, 4603.0, 4936.481669424658, 5321.175217841241, 5482.588805839658, 5525.950069486785, 4879.048585525788, 5290.550303224269, 5305.0, 5581.553475722014, 5659.519395571776, 4358.956410435855, 4664.883377915579, 5210.609204309768, 5431.8098866539485, 5458.41705905283], "value": 4276.3577826839955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.2180149941815, 764.9694012239511, 779.0456690554072, 840.5083026429538, 862.1335557764446, 15032.473863414783, 15680.215989200537, 16181.75727364089, 17098.0, 17115.657686846287, 15291.847081529182, 15564.573409692613, 15726.528204153865, 15885.523434296962, 15910.76133857993, 924.0020777560219, 932.3939439364433, 962.5716556132523, 968.9806203875918, 969.7187815533487, 961.1974001708581, 961.6105477281712, 965.6716716316452, 966.0194902174298, 969.2633598465146, 1028.6451174344838, 1033.2508931025022, 1034.7826956339177, 1040.9375437473775, 1045.6915210013065, 1016.3292227130058, 1022.6574097677287, 1025.4770067265674, 1026.4457193115709, 1028.963986260481, 760.6995236881456, 764.4228607401376, 765.6860687118254, 765.9923400765977, 767.7811823630238, 16835.0, 17178.82821171785, 17365.565860853454, 17412.651746964988, 17512.649747004987, 14168.574942751631, 14245.572632821031, 14340.64148396288, 14372.209528475929, 14505.05717128394, 11773.529058837636, 11920.225185362924, 11982.760344793143, 12032.879671203264, 12547.24716517012, 3650.0, 3699.0, 3738.906527336811, 3835.463035175078, 3859.2281543691092, 93574.0, 111113.88886111116, 119736.40131799372, 120185.0, 120253.79746202513, 10643.73390665224, 10763.569457221623, 10779.784404312026, 11136.33182009082, 11205.663830084948, 23215.767842321697, 23396.0, 23499.53000940006, 23530.294091177293, 23581.646275305884, 7851.882221766678, 7908.169642187488, 7920.089189743138, 7922.7227047053075, 7929.207079292025, 4339.956600434019, 4341.978290108593, 4537.977310113428, 4540.500544940054, 4637.675362724644, 3433.759636825398, 3498.1429549759964, 3507.5089487471773, 3510.4734289856215, 3518.0149558123767, 546.6337553838938, 589.2369381650773, 618.7061145955635, 632.2981490545491, 648.063548172895, 617.3456136495329, 670.852412469256, 686.3582550315471, 689.4105539763531, 700.7965853537464, 9780.853287200556, 10376.010005757298, 10419.843702344471, 10635.52140153695, 10831.458427078614, 653.6013032050446, 667.0328024364691, 686.588047171703, 706.4419108903909, 710.8045287545966, 4174.853880114181, 4973.35346404965, 5042.470540593258, 5046.44489106198, 5075.934053848733, 98997.50501247447, 106226.0, 107603.0, 107724.0, 108600.0, 75268.0, 85403.0, 86474.0, 89078.55460722654, 91095.54452227829, 69754.0, 69992.0, 71421.0, 71476.14095436124, 76345.61827190829, 10304.433256170983, 10644.987959959599, 10956.28784129029, 11048.171387145836, 11388.601398951329, 41363.79318103511, 43058.708238752886, 43930.780346098065, 44452.55547444613, 46644.0, 62963.0, 64537.35462645313, 64735.0, 64933.0, 64940.70118597696, 10431.426271554834, 12620.621381358575, 12877.549285775327, 13042.152260103063, 13374.264415457243, 14328.856711432752, 14646.63383415423, 14731.0, 14777.482788102365, 15269.160196189316, 13550.457981681016, 13686.384112714753, 13816.585502434944, 13854.722905541632, 13908.582742517741, 13320.733585328046, 13601.319934003062, 13721.931390342985, 14069.085509442262, 14089.365978530786, 11080.889191108308, 12996.285204313854, 13277.734445311233, 13307.600771976857, 13772.10481318711, 9395.483248421402, 9610.135087842127, 9704.0, 9910.207183425164, 10118.949405252926, 10927.344359338465, 11361.544851737599, 11502.48238829238, 11503.827442588201, 11539.538418463504], "value": 7709.709311204863, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 126.0, 169.0, 172.0, 121.0, 120.0, 124.0, 162.0, 117.0], "value": 134.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 144.0, 143.0, 164.0, 109.0, 113.0, 113.0, 112.0, 161.0, 140.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [281.0, 256.0, 199.0, 201.0, 197.0, 204.0, 198.0, 198.0, 199.0, 198.0], "value": 213.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 61.0, 99.0, 65.0, 62.0, 61.0, 62.0, 75.0, 62.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 88.0, 74.0, 71.0, 71.0, 81.0, 71.0, 71.0, 86.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 77.0, 136.0, 120.0, 121.0, 90.0, 89.0, 87.0, 89.0, 80.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 103.0, 99.0, 99.0, 97.0, 96.0, 95.0, 120.0, 96.0, 95.0], "value": 105.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 92.0, 91.0, 97.0, 124.0, 134.0, 93.0, 94.0, 88.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 59.0, 82.0, 105.0, 106.0, 106.0, 104.0, 107.0], "value": 87.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 69.0, 50.0, 60.0, 89.0, 84.0, 84.0, 84.0, 60.0, 60.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 91.0, 87.0, 84.0, 81.0, 84.0, 85.0, 91.0, 93.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 149.0, 150.0, 138.0, 142.0, 142.0, 150.0, 206.0, 183.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 168.0, 166.0, 174.0, 168.0, 171.0, 171.0, 170.0, 163.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 65.0, 68.0, 82.0, 85.0, 88.0, 89.0, 91.0, 64.0, 67.0], "value": 78.2, "unit": "score"}]}]} 08:02:36 INFO - Return code: 0 08:02:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:02:36 ERROR - # TBPL SUCCESS # 08:02:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:02:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:02:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:02:36 INFO - 2017-06-16 08:02:36.123 screenresolution[1799:10995] starting screenresolution argv=screenresolution get 08:02:36 INFO - 2017-06-16 08:02:36.126 screenresolution[1799:10995] Display 0: 1600x1200x32@60 08:02:36 INFO - 2017-06-16 08:02:36.133 screenresolution[1800:10998] starting screenresolution argv=screenresolution list 08:02:36 INFO - Available Modes on Display 0 08:02:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:02:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:02:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:02:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:02:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:02:36 INFO - Intel Iris: 08:02:36 INFO - Chipset Model: Intel Iris 08:02:36 INFO - Type: GPU 08:02:36 INFO - Bus: Built-In 08:02:36 INFO - VRAM (Dynamic, Max): 1536 MB 08:02:36 INFO - Vendor: Intel (0x8086) 08:02:36 INFO - Device ID: 0x0a2e 08:02:36 INFO - Revision ID: 0x0009 08:02:36 INFO - Displays: 08:02:36 INFO - Display: 08:02:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:02:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:02:36 INFO - Display Serial Number: 1600x1200 60 08:02:36 INFO - Main Display: Yes 08:02:36 INFO - Mirror: Off 08:02:36 INFO - Online: Yes 08:02:36 INFO - Rotation: Supported 08:02:36 INFO - Return code: 0 08:02:36 INFO - Running post-action listener: _resource_record_post_action 08:02:36 INFO - [mozharness: 2017-06-16 15:02:36.380693Z] Finished run-tests step (success) 08:02:36 INFO - Running post-run listener: _resource_record_post_run 08:02:36 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 40683520; Write bytes: 514944512; Read time: 403; Write time: 2014 08:02:36 INFO - TinderboxPrint: CPU usage
54.6% 08:02:36 INFO - TinderboxPrint: I/O read bytes / time
40,683,520 / 403 08:02:36 INFO - TinderboxPrint: I/O write bytes / time
514,944,512 / 2,014 08:02:36 INFO - TinderboxPrint: CPU idle
2,176.0 (45.4%) 08:02:36 INFO - TinderboxPrint: CPU system
961.8 (20.1%) 08:02:36 INFO - TinderboxPrint: CPU user
1,655.7 (34.5%) 08:02:36 INFO - TinderboxPrint: Swap in / out
248,725,504 / 0 08:02:36 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203930112; Write bytes: 202389504; Read time: 15454; Write time: 416 08:02:36 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 38705152; Write bytes: 301729280; Read time: 382; Write time: 1564 08:02:36 INFO - Running post-run listener: _upload_blobber_files 08:02:36 INFO - Blob upload gear active. 08:02:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:02:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Jamun', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Jamun -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:02:37 INFO - (blobuploader) - INFO - Open directory for files ... 08:02:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:02:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:02:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:38 INFO - (blobuploader) - INFO - Done attempting. 08:02:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:02:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:02:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:39 INFO - (blobuploader) - INFO - Done attempting. 08:02:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:02:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:02:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:40 INFO - (blobuploader) - INFO - Done attempting. 08:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:02:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:42 INFO - (blobuploader) - INFO - Done attempting. 08:02:42 INFO - (blobuploader) - INFO - Iteration through files over. 08:02:42 INFO - Return code: 0 08:02:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:02:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:02:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/aa03a6d0ad70ec7d9aaae53add2b72947a7f78391d746410fbf64bff9caac323e3863088cc265cf47810438f4399ec8e2698218ead386a6b736ede39d6b6c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4845dd48586ab276c79cf447cf83b5f5200ef6ba06682ef8927637380b0195132a2da955f8f19ca33246fbbb1e6c0364cea07f4cb18ef4f52f0b4fd3040f3442", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/137eb96da6cb40de7f16311826adeac62bcb264fc732e251f4c783a779dab5ad62da590f89b5fe89025d9decd1ed30741c8de3803e61961102ee4a7e820d5536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/78f3c239ceb6053cf1a8cbe43f72daa4564fee9b7eca514f57559495d05c969ba5b2aa9ea0f59c15994cfc6ef430b391a365802117b00fd5c85b0cf13f01d43c"} 08:02:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:02:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:02:42 INFO - Contents: 08:02:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/aa03a6d0ad70ec7d9aaae53add2b72947a7f78391d746410fbf64bff9caac323e3863088cc265cf47810438f4399ec8e2698218ead386a6b736ede39d6b6c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4845dd48586ab276c79cf447cf83b5f5200ef6ba06682ef8927637380b0195132a2da955f8f19ca33246fbbb1e6c0364cea07f4cb18ef4f52f0b4fd3040f3442", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/137eb96da6cb40de7f16311826adeac62bcb264fc732e251f4c783a779dab5ad62da590f89b5fe89025d9decd1ed30741c8de3803e61961102ee4a7e820d5536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/78f3c239ceb6053cf1a8cbe43f72daa4564fee9b7eca514f57559495d05c969ba5b2aa9ea0f59c15994cfc6ef430b391a365802117b00fd5c85b0cf13f01d43c"} 08:02:42 INFO - Running post-run listener: copy_logs_to_upload_dir 08:02:42 INFO - Copying logs to upload dir... 08:02:42 INFO - mkdir: /builds/slave/test/build/upload/logs 08:02:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.115559 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-16 08:02:42.440530) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-16 08:02:42.444940) ========= 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.6ONNELHFCz/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.La3qu1NpNZ/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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/Jamun/sha512/aa03a6d0ad70ec7d9aaae53add2b72947a7f78391d746410fbf64bff9caac323e3863088cc265cf47810438f4399ec8e2698218ead386a6b736ede39d6b6c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4845dd48586ab276c79cf447cf83b5f5200ef6ba06682ef8927637380b0195132a2da955f8f19ca33246fbbb1e6c0364cea07f4cb18ef4f52f0b4fd3040f3442", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/137eb96da6cb40de7f16311826adeac62bcb264fc732e251f4c783a779dab5ad62da590f89b5fe89025d9decd1ed30741c8de3803e61961102ee4a7e820d5536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/78f3c239ceb6053cf1a8cbe43f72daa4564fee9b7eca514f57559495d05c969ba5b2aa9ea0f59c15994cfc6ef430b391a365802117b00fd5c85b0cf13f01d43c"} build_url:https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/Jyy9ezz0RFiyBmvD79M6dA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/aa03a6d0ad70ec7d9aaae53add2b72947a7f78391d746410fbf64bff9caac323e3863088cc265cf47810438f4399ec8e2698218ead386a6b736ede39d6b6c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/4845dd48586ab276c79cf447cf83b5f5200ef6ba06682ef8927637380b0195132a2da955f8f19ca33246fbbb1e6c0364cea07f4cb18ef4f52f0b4fd3040f3442", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/137eb96da6cb40de7f16311826adeac62bcb264fc732e251f4c783a779dab5ad62da590f89b5fe89025d9decd1ed30741c8de3803e61961102ee4a7e820d5536", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/78f3c239ceb6053cf1a8cbe43f72daa4564fee9b7eca514f57559495d05c969ba5b2aa9ea0f59c15994cfc6ef430b391a365802117b00fd5c85b0cf13f01d43c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-16 08:02:42.482945) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 08:02:42.483315) ========= 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.6ONNELHFCz/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.La3qu1NpNZ/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.005236 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 08:02:42.532215) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-16 08:02:42.532548) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-16 08:02:42.532862) ========= ========= Total master_lag: 0.20 =========