builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0330 starttime: 1496398740.79 results: success (0) buildid: 20170602013224 builduid: 634fd97e0fb84bbfacb21e74f2fd62fe revision: 4f0bd6c5b03dafa6025621da30e1bc87008d027b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:00.787683) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:00.788126) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:00.788403) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:00.812431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:00.812828) ========= 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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-02 03:19:00-- 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% 41.3M=0s 2017-06-02 03:19:01 (41.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.301783 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:01.136941) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:01.137305) ========= 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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.021321 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:01.186694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 03:19:01.187122) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4f0bd6c5b03dafa6025621da30e1bc87008d027b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4f0bd6c5b03dafa6025621da30e1bc87008d027b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GGmO3F9OTr/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.IwcsnCcnn2/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-02 03:19:01,269 truncating revision to first 12 chars 2017-06-02 03:19:01,270 Setting DEBUG logging. 2017-06-02 03:19:01,270 attempt 1/10 2017-06-02 03:19:01,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4f0bd6c5b03d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 03:19:02,366 unpacking tar archive at: mozilla-beta-4f0bd6c5b03d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.369200 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 03:19:02.578965) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:02.579291) ========= script_repo_revision: 4f0bd6c5b03dafa6025621da30e1bc87008d027b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:02.579673) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:02.579957) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 03:19:02.598607) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-06-02 03:19:02.599043) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GGmO3F9OTr/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.IwcsnCcnn2/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:19:02 INFO - MultiFileLogger online at 20170602 03:19:02 in /builds/slave/test 03:19:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:19:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:19:02 INFO - {'append_to_log': False, 03:19:02 INFO - 'base_work_dir': '/builds/slave/test', 03:19:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:19:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:19:02 INFO - 'branch': 'Mozilla-Beta', 03:19:02 INFO - 'buildbot_json_path': 'buildprops.json', 03:19:02 INFO - 'config_files': ('talos/mac_config.py',), 03:19:02 INFO - 'default_actions': ('clobber', 03:19:02 INFO - 'read-buildbot-config', 03:19:02 INFO - 'download-and-extract', 03:19:02 INFO - 'populate-webroot', 03:19:02 INFO - 'create-virtualenv', 03:19:02 INFO - 'install', 03:19:02 INFO - 'run-tests'), 03:19:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:19:02 INFO - 'download_minidump_stackwalk': True, 03:19:02 INFO - 'download_symbols': 'ondemand', 03:19:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:19:02 INFO - 'tooltool.py': '/tools/tooltool.py', 03:19:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:19:02 INFO - '/tools/misc-python/virtualenv.py')}, 03:19:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:19:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:19:02 INFO - 'gecko_profile': False, 03:19:02 INFO - 'gecko_profile_interval': 0, 03:19:02 INFO - 'installer_path': 'installer.exe', 03:19:02 INFO - 'log_level': 'info', 03:19:02 INFO - 'log_name': 'talos', 03:19:02 INFO - 'log_to_console': True, 03:19:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:19:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:19:02 INFO - 'opt_config_files': (), 03:19:02 INFO - 'pip_index': False, 03:19:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:19:02 INFO - 'cmd': ('bash', 03:19:02 INFO - '-c', 03:19:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:19:02 INFO - 'enabled': True, 03:19:02 INFO - 'halt_on_failure': False, 03:19:02 INFO - 'name': 'check_screen_resolution'},), 03:19:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:19:02 INFO - 'cmd': ('bash', 03:19:02 INFO - '-c', 03:19:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:19:02 INFO - 'enabled': True, 03:19:02 INFO - 'halt_on_failure': False, 03:19:02 INFO - 'name': 'check_screen_resolution'},), 03:19:02 INFO - 'run_cmd_checks_enabled': True, 03:19:02 INFO - 'suite': 'dromaeojs', 03:19:02 INFO - 'system_bits': '32', 03:19:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:19:02 INFO - 'title': 't-yosemite-r7-0330', 03:19:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:19:02 INFO - 'use_talos_json': True, 03:19:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:19:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:19:02 INFO - 'work_dir': 'build'} 03:19:02 INFO - [mozharness: 2017-06-02 10:19:02.798825Z] Running clobber step. 03:19:02 INFO - Running pre-action listener: _resource_record_pre_action 03:19:02 INFO - Running main action method: clobber 03:19:02 INFO - rmtree: /builds/slave/test/build 03:19:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:19:02 INFO - Running post-action listener: _resource_record_post_action 03:19:02 INFO - [mozharness: 2017-06-02 10:19:02.954251Z] Finished clobber step (success) 03:19:02 INFO - [mozharness: 2017-06-02 10:19:02.954393Z] Running read-buildbot-config step. 03:19:02 INFO - Running pre-action listener: _resource_record_pre_action 03:19:02 INFO - Running main action method: read_buildbot_config 03:19:02 INFO - Using buildbot properties: 03:19:02 INFO - { 03:19:02 INFO - "project": "", 03:19:02 INFO - "product": "firefox", 03:19:02 INFO - "script_repo_revision": "production", 03:19:02 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 03:19:02 INFO - "repository": "", 03:19:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 03:19:02 INFO - "buildid": "20170602013224", 03:19:02 INFO - "pgo_build": "False", 03:19:02 INFO - "basedir": "/builds/slave/test", 03:19:02 INFO - "buildnumber": 13, 03:19:02 INFO - "slavename": "t-yosemite-r7-0330", 03:19:02 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:19:02 INFO - "platform": "yosemite_r7", 03:19:02 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:19:02 INFO - "branch": "mozilla-beta", 03:19:02 INFO - "repo_path": "releases/mozilla-beta", 03:19:02 INFO - "revision": "4f0bd6c5b03dafa6025621da30e1bc87008d027b", 03:19:02 INFO - "stage_platform": "macosx64", 03:19:02 INFO - "builduid": "634fd97e0fb84bbfacb21e74f2fd62fe", 03:19:02 INFO - "slavebuilddir": "test" 03:19:02 INFO - } 03:19:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 03:19:02 INFO - Running post-action listener: _resource_record_post_action 03:19:02 INFO - [mozharness: 2017-06-02 10:19:02.957921Z] Finished read-buildbot-config step (success) 03:19:02 INFO - [mozharness: 2017-06-02 10:19:02.958039Z] Running download-and-extract step. 03:19:02 INFO - Running pre-action listener: _resource_record_pre_action 03:19:02 INFO - Running main action method: download_and_extract 03:19:02 INFO - mkdir: /builds/slave/test/build/tests 03:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:02 INFO - trying https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 03:19:02 INFO - Downloading https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/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 03:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/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 03:19:03 INFO - Downloaded 1287 bytes. 03:19:03 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 03:19:03 INFO - Using the following test package requirements: 03:19:03 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 03:19:03 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 03:19:03 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 03:19:03 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 03:19:03 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:19:03 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 03:19:03 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 03:19:03 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 03:19:03 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 03:19:03 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 03:19:03 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:19:03 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 03:19:03 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 03:19:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 03:19:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 03:19:03 INFO - Fetch https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 03:19:05 INFO - Expected file size: 51393672 03:19:05 INFO - Obtained file size: 51393672 03:19:21 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 03:19:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 03:19:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 03:19:21 INFO - Fetch https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 03:19:23 INFO - Expected file size: 51393672 03:19:23 INFO - Obtained file size: 51393672 03:19:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 03:19:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 03:19:38 INFO - Fetch https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 03:19:39 INFO - Expected file size: 14404461 03:19:39 INFO - Obtained file size: 14404461 03:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:39 INFO - trying https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:19:39 INFO - Downloading https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 03:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/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 03:19:42 INFO - Downloaded 59645441 bytes. 03:19:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:19:42 INFO - mkdir: /builds/slave/test/properties 03:19:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:19:42 INFO - Writing to file /builds/slave/test/properties/build_url 03:19:42 INFO - Contents: 03:19:42 INFO - build_url:https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:19:43 INFO - Running post-action listener: _resource_record_post_action 03:19:43 INFO - Running post-action listener: set_extra_try_arguments 03:19:43 INFO - [mozharness: 2017-06-02 10:19:43.044208Z] Finished download-and-extract step (success) 03:19:43 INFO - [mozharness: 2017-06-02 10:19:43.044384Z] Running populate-webroot step. 03:19:43 INFO - Running pre-action listener: _resource_record_pre_action 03:19:43 INFO - Running main action method: populate_webroot 03:19:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:19:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:19:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:19:43 INFO - '--tppagecycles', 03:19:43 INFO - '7'], 03:19:43 INFO - 'tests': ['tsvgm']}}, 03:19:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:19:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:19:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:19:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:19:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:19:43 INFO - 'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:19:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:19:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:19:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:19:43 INFO - 'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['damp', 'tps']}, 03:19:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:19:43 INFO - 'tests': ['damp', 'tps']}, 03:19:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['dromaeo_dom']}, 03:19:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:19:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['basic_compositor_video']}, 03:19:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:19:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['a11yr', 03:19:43 INFO - 'ts_paint', 03:19:43 INFO - 'tpaint', 03:19:43 INFO - 'sessionrestore', 03:19:43 INFO - 'sessionrestore_no_auto_restore', 03:19:43 INFO - 'tabpaint']}, 03:19:43 INFO - 'other-e10s': {'tests': ['a11yr', 03:19:43 INFO - 'ts_paint', 03:19:43 INFO - 'tpaint', 03:19:43 INFO - 'sessionrestore', 03:19:43 INFO - 'sessionrestore_no_auto_restore', 03:19:43 INFO - 'tabpaint']}, 03:19:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:19:43 INFO - 'ts_paint', 03:19:43 INFO - 'tpaint', 03:19:43 INFO - 'sessionrestore', 03:19:43 INFO - 'sessionrestore_no_auto_restore', 03:19:43 INFO - 'tabpaint']}, 03:19:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:19:43 INFO - 'ts_paint', 03:19:43 INFO - 'tpaint', 03:19:43 INFO - 'sessionrestore', 03:19:43 INFO - 'sessionrestore_no_auto_restore', 03:19:43 INFO - 'tabpaint']}, 03:19:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['a11yr', 03:19:43 INFO - 'ts_paint', 03:19:43 INFO - 'tpaint', 03:19:43 INFO - 'sessionrestore', 03:19:43 INFO - 'sessionrestore_no_auto_restore', 03:19:43 INFO - 'tabpaint']}, 03:19:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['a11yr', 03:19:43 INFO - 'ts_paint', 03:19:43 INFO - 'tpaint', 03:19:43 INFO - 'sessionrestore', 03:19:43 INFO - 'sessionrestore_no_auto_restore', 03:19:43 INFO - 'tabpaint']}, 03:19:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['tsvgx', 03:19:43 INFO - 'tsvgr_opacity', 03:19:43 INFO - 'tart', 03:19:43 INFO - 'tscrollx', 03:19:43 INFO - 'cart', 03:19:43 INFO - 'tsvg_static']}, 03:19:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:19:43 INFO - 'tsvgr_opacity', 03:19:43 INFO - 'tart', 03:19:43 INFO - 'tscrollx', 03:19:43 INFO - 'cart', 03:19:43 INFO - 'tsvg_static']}, 03:19:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:19:43 INFO - 'talos_options': ['--disable-e10s'], 03:19:43 INFO - 'tests': ['tp5o']}, 03:19:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:19:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:19:43 INFO - 'talos_options': ['--disable-e10s', 03:19:43 INFO - '--xperf_path', 03:19:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:19:43 INFO - 'tests': ['tp5n']}, 03:19:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:19:43 INFO - 'talos_options': ['--xperf_path', 03:19:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:19:43 INFO - 'tests': ['tp5n']}}, 03:19:43 INFO - 'talos.zip': {'path': '', 03:19:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:19:43 INFO - Running post-action listener: _resource_record_post_action 03:19:43 INFO - [mozharness: 2017-06-02 10:19:43.062615Z] Finished populate-webroot step (success) 03:19:43 INFO - [mozharness: 2017-06-02 10:19:43.062752Z] Running create-virtualenv step. 03:19:43 INFO - Running pre-action listener: _resource_record_pre_action 03:19:43 INFO - Running main action method: create_virtualenv 03:19:43 INFO - Creating virtualenv /builds/slave/test/build/venv 03:19:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:19:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:19:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:19:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:19:43 INFO - Using real prefix '/tools/python27' 03:19:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:19:43 INFO - Installing distribute.............................................................................................................................................................................................done. 03:19:46 INFO - Installing pip.................done. 03:19:46 INFO - Return code: 0 03:19:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:19:47 INFO - Reading from file tmpfile_stdout 03:19:47 INFO - Output received: 03:19:47 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) 03:19:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:47 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:19:47 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 03:19:47 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 03:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:19:47 INFO - 'HOME': '/Users/cltbld', 03:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:19:47 INFO - 'LOGNAME': 'cltbld', 03:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:47 INFO - 'MOZ_NO_REMOTE': '1', 03:19:47 INFO - 'NO_EM_RESTART': '1', 03:19:47 INFO - 'PAGER': '/bin/cat', 03:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:47 INFO - 'PWD': '/builds/slave/test', 03:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:19:47 INFO - 'SHELL': '/bin/bash', 03:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:19:47 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:19:47 INFO - 'USER': 'cltbld', 03:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:47 INFO - 'XPC_FLAGS': '0x0', 03:19:47 INFO - 'XPC_SERVICE_NAME': '0', 03:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:19:47 INFO - Cleaning up... 03:19:47 INFO - Return code: 0 03:19:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:47 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:19:47 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 03:19:47 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 03:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:19:47 INFO - 'HOME': '/Users/cltbld', 03:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:19:47 INFO - 'LOGNAME': 'cltbld', 03:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:47 INFO - 'MOZ_NO_REMOTE': '1', 03:19:47 INFO - 'NO_EM_RESTART': '1', 03:19:47 INFO - 'PAGER': '/bin/cat', 03:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:47 INFO - 'PWD': '/builds/slave/test', 03:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:19:47 INFO - 'SHELL': '/bin/bash', 03:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:19:47 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:19:47 INFO - 'USER': 'cltbld', 03:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:47 INFO - 'XPC_FLAGS': '0x0', 03:19:47 INFO - 'XPC_SERVICE_NAME': '0', 03:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:47 INFO - Downloading/unpacking psutil>=3.1.1 03:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:19:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:19:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:19:49 INFO - Installing collected packages: psutil 03:19:49 INFO - Running setup.py install for psutil 03:19:49 INFO - building 'psutil._psutil_osx' extension 03:19:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:19:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:19:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:19:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:19:50 INFO - building 'psutil._psutil_posix' extension 03:19:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:19:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:19:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:19:50 INFO - ^ 03:19:50 INFO - 1 warning generated. 03:19:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:19:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:19:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:19:50 INFO - Successfully installed psutil 03:19:50 INFO - Cleaning up... 03:19:50 INFO - Return code: 0 03:19:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:50 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:19:50 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 03:19:50 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 03:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:19:50 INFO - 'HOME': '/Users/cltbld', 03:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:19:50 INFO - 'LOGNAME': 'cltbld', 03:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:50 INFO - 'MOZ_NO_REMOTE': '1', 03:19:50 INFO - 'NO_EM_RESTART': '1', 03:19:50 INFO - 'PAGER': '/bin/cat', 03:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:50 INFO - 'PWD': '/builds/slave/test', 03:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:19:50 INFO - 'SHELL': '/bin/bash', 03:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:19:50 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:19:50 INFO - 'USER': 'cltbld', 03:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:50 INFO - 'XPC_FLAGS': '0x0', 03:19:50 INFO - 'XPC_SERVICE_NAME': '0', 03:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:19:52 INFO - Installing collected packages: mozsystemmonitor 03:19:52 INFO - Running setup.py install for mozsystemmonitor 03:19:52 INFO - Successfully installed mozsystemmonitor 03:19:52 INFO - Cleaning up... 03:19:52 INFO - Return code: 0 03:19:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:19:52 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 03:19:52 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 03:19:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:19:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:19:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:19:52 INFO - 'HOME': '/Users/cltbld', 03:19:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:19:52 INFO - 'LOGNAME': 'cltbld', 03:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:52 INFO - 'MOZ_NO_REMOTE': '1', 03:19:52 INFO - 'NO_EM_RESTART': '1', 03:19:52 INFO - 'PAGER': '/bin/cat', 03:19:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:52 INFO - 'PWD': '/builds/slave/test', 03:19:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:19:52 INFO - 'SHELL': '/bin/bash', 03:19:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:19:52 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:19:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:19:52 INFO - 'USER': 'cltbld', 03:19:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:19:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:52 INFO - 'XPC_FLAGS': '0x0', 03:19:52 INFO - 'XPC_SERVICE_NAME': '0', 03:19:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:52 INFO - Downloading/unpacking jsonschema==2.5.1 03:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:19:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:54 INFO - Downloading functools32-3.2.3-2.tar.gz 03:19:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:19:54 INFO - warning: no files found matching '*.txt' 03:19:54 INFO - no previously-included directories found matching 'build' 03:19:54 INFO - no previously-included directories found matching 'dist' 03:19:54 INFO - no previously-included directories found matching '.git*' 03:19:54 INFO - Installing collected packages: jsonschema, functools32 03:19:54 INFO - Running setup.py install for functools32 03:19:54 INFO - warning: no files found matching '*.txt' 03:19:54 INFO - no previously-included directories found matching 'build' 03:19:54 INFO - no previously-included directories found matching 'dist' 03:19:54 INFO - no previously-included directories found matching '.git*' 03:19:54 INFO - Successfully installed jsonschema functools32 03:19:54 INFO - Cleaning up... 03:19:54 INFO - Return code: 0 03:19:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:54 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:19:54 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 03:19:54 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 03:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:19:54 INFO - 'HOME': '/Users/cltbld', 03:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:19:54 INFO - 'LOGNAME': 'cltbld', 03:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:54 INFO - 'MOZ_NO_REMOTE': '1', 03:19:54 INFO - 'NO_EM_RESTART': '1', 03:19:54 INFO - 'PAGER': '/bin/cat', 03:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:54 INFO - 'PWD': '/builds/slave/test', 03:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:19:54 INFO - 'SHELL': '/bin/bash', 03:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:19:54 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:19:54 INFO - 'USER': 'cltbld', 03:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:54 INFO - 'XPC_FLAGS': '0x0', 03:19:54 INFO - 'XPC_SERVICE_NAME': '0', 03:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:19:54 INFO - Cleaning up... 03:19:54 INFO - Return code: 0 03:19:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:54 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:19:54 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 03:19:54 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 03:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:19:54 INFO - 'HOME': '/Users/cltbld', 03:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:19:54 INFO - 'LOGNAME': 'cltbld', 03:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:54 INFO - 'MOZ_NO_REMOTE': '1', 03:19:54 INFO - 'NO_EM_RESTART': '1', 03:19:54 INFO - 'PAGER': '/bin/cat', 03:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:54 INFO - 'PWD': '/builds/slave/test', 03:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:19:54 INFO - 'SHELL': '/bin/bash', 03:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:19:54 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:19:54 INFO - 'USER': 'cltbld', 03:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:54 INFO - 'XPC_FLAGS': '0x0', 03:19:54 INFO - 'XPC_SERVICE_NAME': '0', 03:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:19:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:55 INFO - Downloading/unpacking blobuploader==1.2.4 03:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:19:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:19:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:57 INFO - Downloading docopt-0.6.1.tar.gz 03:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:19:57 INFO - Installing collected packages: blobuploader, requests, docopt 03:19:57 INFO - Running setup.py install for blobuploader 03:19:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:19:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:19:57 INFO - Running setup.py install for requests 03:19:58 INFO - Running setup.py install for docopt 03:19:58 INFO - Successfully installed blobuploader requests docopt 03:19:58 INFO - Cleaning up... 03:19:58 INFO - Return code: 0 03:19:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:59 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:19:59 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 03:19:59 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 03:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:19:59 INFO - 'HOME': '/Users/cltbld', 03:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:19:59 INFO - 'LOGNAME': 'cltbld', 03:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:59 INFO - 'MOZ_NO_REMOTE': '1', 03:19:59 INFO - 'NO_EM_RESTART': '1', 03:19:59 INFO - 'PAGER': '/bin/cat', 03:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:59 INFO - 'PWD': '/builds/slave/test', 03:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:19:59 INFO - 'SHELL': '/bin/bash', 03:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:19:59 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:19:59 INFO - 'USER': 'cltbld', 03:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:59 INFO - 'XPC_FLAGS': '0x0', 03:19:59 INFO - 'XPC_SERVICE_NAME': '0', 03:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-T225ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-MX2yAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-xI_Vvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ABshIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-3UIOXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-aw4cmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:19:59 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-3FoE1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-HeXy9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-fbHtGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-PJzJhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-vKnsyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-skI5rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Zn1aNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9n73yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-zJf2uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:20:00 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-RsUx5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:20:01 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-bvYQUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:20:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:20:01 INFO - Running setup.py install for manifestparser 03:20:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:20:01 INFO - Running setup.py install for mozcrash 03:20:01 INFO - Running setup.py install for mozdebug 03:20:01 INFO - Running setup.py install for mozdevice 03:20:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:20:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:20:01 INFO - Running setup.py install for mozfile 03:20:01 INFO - Running setup.py install for mozhttpd 03:20:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Running setup.py install for mozinfo 03:20:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Running setup.py install for mozInstall 03:20:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Running setup.py install for mozleak 03:20:02 INFO - Running setup.py install for mozlog 03:20:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Running setup.py install for moznetwork 03:20:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:20:02 INFO - Running setup.py install for mozprocess 03:20:02 INFO - Running setup.py install for mozprofile 03:20:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:20:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:20:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:20:03 INFO - Running setup.py install for mozrunner 03:20:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:20:03 INFO - Running setup.py install for mozscreenshot 03:20:03 INFO - Running setup.py install for moztest 03:20:03 INFO - Running setup.py install for mozversion 03:20:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:20:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:20:03 INFO - Cleaning up... 03:20:03 INFO - Return code: 0 03:20:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:04 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:20:04 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 03:20:04 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 03:20:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:20:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:04 INFO - 'HOME': '/Users/cltbld', 03:20:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:04 INFO - 'LOGNAME': 'cltbld', 03:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:04 INFO - 'MOZ_NO_REMOTE': '1', 03:20:04 INFO - 'NO_EM_RESTART': '1', 03:20:04 INFO - 'PAGER': '/bin/cat', 03:20:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:04 INFO - 'PWD': '/builds/slave/test', 03:20:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:04 INFO - 'SHELL': '/bin/bash', 03:20:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:20:04 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:20:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:04 INFO - 'USER': 'cltbld', 03:20:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:04 INFO - 'XPC_FLAGS': '0x0', 03:20:04 INFO - 'XPC_SERVICE_NAME': '0', 03:20:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-8KBTIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-hQmdIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:20:04 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)) 03:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-TfbzKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-mWfRZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:20:04 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)) 03:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-5YY3mM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-c6I_tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:20:04 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-DxXwXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Hz_4ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-cC_zsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-fW4H87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:20:05 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)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-A62FSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-e4h6cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:20:05 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)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-gAjUQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-YWb3h_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:20:05 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)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-75lyp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:20:05 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-CJrtUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:20:06 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)) 03:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:20:06 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-nOhd13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:20:06 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)) 03:20:06 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)) 03:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:20:06 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)) 03:20:06 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)) 03:20:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:20:07 INFO - Downloading blessings-1.6.tar.gz 03:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:20:08 INFO - Installing collected packages: blessings 03:20:08 INFO - Running setup.py install for blessings 03:20:08 INFO - Successfully installed blessings 03:20:08 INFO - Cleaning up... 03:20:08 INFO - Return code: 0 03:20:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:20:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:20:08 INFO - Reading from file tmpfile_stdout 03:20:08 INFO - Current package versions: 03:20:08 INFO - blessings == 1.6 03:20:08 INFO - blobuploader == 1.2.4 03:20:08 INFO - docopt == 0.6.1 03:20:08 INFO - functools32 == 3.2.3-2 03:20:08 INFO - jsonschema == 2.5.1 03:20:08 INFO - manifestparser == 1.1 03:20:08 INFO - mozInstall == 1.12 03:20:08 INFO - mozcrash == 1.0 03:20:08 INFO - mozdebug == 0.1 03:20:08 INFO - mozdevice == 0.49 03:20:08 INFO - mozfile == 1.2 03:20:08 INFO - mozhttpd == 0.7 03:20:08 INFO - mozinfo == 0.9 03:20:08 INFO - mozleak == 0.1 03:20:08 INFO - mozlog == 3.4 03:20:08 INFO - moznetwork == 0.27 03:20:08 INFO - mozprocess == 0.25 03:20:08 INFO - mozprofile == 0.28 03:20:08 INFO - mozrunner == 6.13 03:20:08 INFO - mozscreenshot == 0.1 03:20:08 INFO - mozsystemmonitor == 0.3 03:20:08 INFO - moztest == 0.8 03:20:08 INFO - mozversion == 1.4 03:20:08 INFO - psutil == 3.1.1 03:20:08 INFO - requests == 1.2.3 03:20:08 INFO - wsgiref == 0.1.2 03:20:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:08 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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:20:08 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 03:20:08 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 03:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:08 INFO - 'HOME': '/Users/cltbld', 03:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:08 INFO - 'LOGNAME': 'cltbld', 03:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:08 INFO - 'MOZ_NO_REMOTE': '1', 03:20:08 INFO - 'NO_EM_RESTART': '1', 03:20:08 INFO - 'PAGER': '/bin/cat', 03:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:08 INFO - 'PWD': '/builds/slave/test', 03:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:08 INFO - 'SHELL': '/bin/bash', 03:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:20:08 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:08 INFO - 'USER': 'cltbld', 03:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:08 INFO - 'XPC_FLAGS': '0x0', 03:20:08 INFO - 'XPC_SERVICE_NAME': '0', 03:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:20:08 INFO - Cleaning up... 03:20:08 INFO - Return code: 0 03:20:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10ac81b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac84768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bcc8250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, '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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:20:08 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 03:20:08 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 03:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:08 INFO - 'HOME': '/Users/cltbld', 03:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:08 INFO - 'LOGNAME': 'cltbld', 03:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:08 INFO - 'MOZ_NO_REMOTE': '1', 03:20:08 INFO - 'NO_EM_RESTART': '1', 03:20:08 INFO - 'PAGER': '/bin/cat', 03:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:08 INFO - 'PWD': '/builds/slave/test', 03:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:08 INFO - 'SHELL': '/bin/bash', 03:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:20:08 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:08 INFO - 'USER': 'cltbld', 03:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:08 INFO - 'XPC_FLAGS': '0x0', 03:20:08 INFO - 'XPC_SERVICE_NAME': '0', 03:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:20:09 INFO - Cleaning up... 03:20:09 INFO - Return code: 0 03:20:09 INFO - Running post-action listener: _resource_record_post_action 03:20:09 INFO - Running post-action listener: _start_resource_monitoring 03:20:09 INFO - Starting resource monitoring. 03:20:09 INFO - [mozharness: 2017-06-02 10:20:09.077452Z] Finished create-virtualenv step (success) 03:20:09 INFO - [mozharness: 2017-06-02 10:20:09.078388Z] Running install step. 03:20:09 INFO - Running pre-action listener: _resource_record_pre_action 03:20:09 INFO - Running main action method: install 03:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:20:09 INFO - Reading from file tmpfile_stdout 03:20:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:20:09 INFO - Reading from file tmpfile_stdout 03:20:09 INFO - Output received: 03:20:09 INFO - Usage: mozinstall [options] installer 03:20:09 INFO - Options: 03:20:09 INFO - -h, --help show this help message and exit 03:20:09 INFO - -d DEST, --destination=DEST 03:20:09 INFO - Directory to install application into. [default: 03:20:09 INFO - "/builds/slave/test"] 03:20:09 INFO - --app=APP Application being installed. [default: firefox] 03:20:09 INFO - mkdir: /builds/slave/test/build/application 03:20:09 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'] 03:20:09 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 03:20:24 INFO - Reading from file tmpfile_stdout 03:20:24 INFO - Output received: 03:20:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 03:20:24 INFO - Running post-action listener: _resource_record_post_action 03:20:24 INFO - [mozharness: 2017-06-02 10:20:24.264806Z] Finished install step (success) 03:20:24 INFO - [mozharness: 2017-06-02 10:20:24.264920Z] Running run-tests step. 03:20:24 INFO - Running pre-action listener: _resource_record_pre_action 03:20:24 INFO - Running main action method: run_tests 03:20:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:20:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:20:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:20:24 INFO - 2017-06-02 03:20:24.279 screenresolution[1904:8955] starting screenresolution argv=screenresolution get 03:20:24 INFO - 2017-06-02 03:20:24.282 screenresolution[1904:8955] Display 0: 1600x1200x32@60 03:20:24 INFO - 2017-06-02 03:20:24.288 screenresolution[1905:8958] starting screenresolution argv=screenresolution list 03:20:24 INFO - Available Modes on Display 0 03:20:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:20:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:20:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:20:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:20:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:20:24 INFO - Intel Iris: 03:20:24 INFO - Chipset Model: Intel Iris 03:20:24 INFO - Type: GPU 03:20:24 INFO - Bus: Built-In 03:20:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:20:24 INFO - Vendor: Intel (0x8086) 03:20:24 INFO - Device ID: 0x0a2e 03:20:24 INFO - Revision ID: 0x0009 03:20:24 INFO - Displays: 03:20:24 INFO - Display: 03:20:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:20:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:20:24 INFO - Display Serial Number: 1600x1200 60 03:20:24 INFO - Main Display: Yes 03:20:24 INFO - Mirror: Off 03:20:24 INFO - Online: Yes 03:20:24 INFO - Rotation: Supported 03:20:24 INFO - Return code: 0 03:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:20:24 INFO - Python 2.7.3 03:20:24 INFO - Return code: 0 03:20:24 INFO - grabbing minidump binary from tooltool 03:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:20:24 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 0x10aa5fd30>, '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 0x10a986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:20:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:20:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:20:24 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 03:20:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:20:24 INFO - Return code: 0 03:20:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:20:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:20:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:20:24 INFO - ENV: RUST_BACKTRACE is now 1 03:20:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:20:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:20:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GGmO3F9OTr/Render', 03:20:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:24 INFO - 'HOME': '/Users/cltbld', 03:20:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:24 INFO - 'LOGNAME': 'cltbld', 03:20:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:20:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:24 INFO - 'MOZ_NO_REMOTE': '1', 03:20:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:20:24 INFO - 'NO_EM_RESTART': '1', 03:20:24 INFO - 'PAGER': '/bin/cat', 03:20:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:24 INFO - 'PWD': '/builds/slave/test', 03:20:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:20:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:24 INFO - 'RUST_BACKTRACE': '1', 03:20:24 INFO - 'SHELL': '/bin/bash', 03:20:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IwcsnCcnn2/Listeners', 03:20:24 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 03:20:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:24 INFO - 'USER': 'cltbld', 03:20:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:24 INFO - 'XPC_FLAGS': '0x0', 03:20:24 INFO - 'XPC_SERVICE_NAME': '0', 03:20:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:20:24 INFO - mozversion application_buildid: 20170602013224 03:20:24 INFO - mozversion application_changeset: 4f0bd6c5b03dafa6025621da30e1bc87008d027b 03:20:24 INFO - mozversion application_display_name: Firefox 03:20:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:20:24 INFO - mozversion application_name: Firefox 03:20:24 INFO - mozversion application_remotingname: firefox 03:20:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:20:24 INFO - mozversion application_vendor: Mozilla 03:20:24 INFO - mozversion application_version: 54.0 03:20:24 INFO - mozversion platform_buildid: 20170602013224 03:20:24 INFO - mozversion platform_changeset: 4f0bd6c5b03dafa6025621da30e1bc87008d027b 03:20:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:20:24 INFO - mozversion platform_version: 54.0 03:20:24 INFO - using testdate: 1496398824 03:20:24 INFO - actual date: 1496398824 03:20:24 INFO - starting webserver on 'localhost:49347' 03:20:24 INFO - SUITE-START | Running 2 tests 03:20:24 INFO - TEST-START | dromaeo_css 03:20:24 INFO - operating with platform_type : mac_ 03:20:24 INFO - Initialising browser for dromaeo_css test... 03:20:24 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp8mM5tE/profile http://localhost:49347/getInfo.html) 03:20:27 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 03:20:27 INFO - PROCESS | 1915 | colorDepth:24 03:20:27 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 03:20:27 INFO - PROCESS | 1915 | __metrics 03:20:27 INFO - PROCESS | 1915 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:20:27 INFO - PROCESS | 1915 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:20:27 INFO - TEST-INFO | 1915: exit 0 03:20:27 INFO - Browser initialized. 03:20:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:20:27 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp8mM5tE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:39 INFO - PROCESS | 1916 | RSS: Main: 165527552 03:20:39 INFO - PROCESS | 1916 | 03:23:44 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49347/tests/dromaeo/cssquery-ext.html) 03:23:45 INFO - PROCESS | 1916 | RSS: Main: 331399168 03:23:45 INFO - PROCESS | 1916 | 03:26:48 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-ext.html (next: http://localhost:49347/tests/dromaeo/cssquery-jquery.html) 03:26:49 INFO - PROCESS | 1916 | RSS: Main: 304082944 03:26:49 INFO - PROCESS | 1916 | 03:29:51 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49347/tests/dromaeo/cssquery-mootools.html) 03:29:52 INFO - PROCESS | 1916 | RSS: Main: 300797952 03:29:52 INFO - PROCESS | 1916 | 03:32:54 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49347/tests/dromaeo/cssquery-prototype.html) 03:32:55 INFO - PROCESS | 1916 | RSS: Main: 297349120 03:32:55 INFO - PROCESS | 1916 | 03:35:59 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49347/tests/dromaeo/cssquery-yui.html) 03:35:59 INFO - PROCESS | 1916 | RSS: Main: 269340672 03:35:59 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-yui.html (next: http://localhost:49347/tests/dromaeo/cssquery-dojo.html) 03:39:03 INFO - PROCESS | 1916 | RSS: Main: 341676032 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | __start_tp_report 03:39:03 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 03:39:03 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 03:39:03 INFO - PROCESS | 1916 | |i|pagename|runs| 03:39:03 INFO - PROCESS | 1916 | |0;dojo.html;3560.1455650643843;3561.608223095459;3579.7315201359906;3621.130928577141;3626.87305944292;6154.938450615493;6160.537959653033;6171.259448866137;6175.351588083248;6176.53675974302;6107.1449997000345;6174.444300012998;6178.382161783819;6179.721912513936;6184;5533.418991005947;5552.77788888444;5594.524465420444;5628.605997580171;5727;5478.369987451434;5484.91772623411;5491.533219676332;5717.971410142944;5727.684977326246;5587.636803607773;5593.720313984305;5595.132754423071;5599.468050535193;5605.775768969236;5576.553875689935;5583.748731307101;5586.24585680932;5594.049011668006;5600.831975040755;4693.366395536627;5378.220158077096;5553.527950124244;5554.416786237468;5557.833265002057;6191.0713392990965;6208.9689551551955;6212.378762123797;6213.254409470847;6218.595791273553;6357.618542887441;6500.4474619657385;6534.052562378429;6544.509161812883;6546.967265163691;6676.198856137246;6686.6656667166935;6688;6690.565113267622;6693.297203793629;6221.191245138149;6231.501479881598;6250.312465628777;6261.655608941552;6315.115883776275;6545.37818907203;6602.900956485657;6674.098996635489;6717.832054198587;6761.864762704817;6491.740330386826;6498.675066246668;6510.251321098134;6511.609303441809;6524;6657.700403481855;6664.5334826561675;6666.699998500079;6673.666316684244;6679.131712877392;6144.218176728698;6380.425761681471;6574.342565743483;6582.868342633121;6591;6454.709538070798;6455.967720161463;6556.63938483388;6567.638779867153;6579.967100164565;5317.654352467077;5336.1195402758885;5339.11904535755;5341.359036915595;5367.89958058597;4639.698419602715;4684.812607495662;4735.408073990751;4736.881577960578;4745.193317136026;6193.504519638418;6200.3799620037635;6200.56596038282;6257.968710156511;6263.4362907337645;6674.432673222734;6676.165479315086;6678.23200331968;6680.665966701743;6684.63234522106;6296.275928268308;6304.621722696653;6317.4946004319545;6317.747290108344;6337.7781777637565;5638.718064096861;5661.745221465044;5718.370979192286;5758.884822303614;5787.971060144672;6841.623710696059;6884.242733299433;6923.792286231421;6928.549644273106;6940.340667636567;6583.934160658524;6631.900521492085;6652.101966234496;6656.633885136364;6668.233153187251;6609.107770450928;6656.7670131545165;6895.620740859301;6918.930810691829;6925.861482770417;6462.741490340334;6512.283648798723;6534.738610455529;6576.112224849584;6578.736850525926;6660.333966603494;6665.866682666417;6669.233038200669;6673.032410300576;6673.432758215606;6221.564490485623;6335.588186767845;6461.289258181502;6474.514411419068;6489.7404103835315;6277.96861015692;6350.079238510298;6400.199975003125;6471.094046833353;6473.676316184266;6348.77779277723;6360.618362898241;6361.93638063632;6365.745370185141;6377.8724425512155;4919;6255;6269.96865015672;6336.112944187909;6466.159399278064;6275.215598050244;6296.370362963667;6297.96851015742;6335;6337.588056776295 03:39:03 INFO - PROCESS | 1916 | |1;ext.html;773.9651715672794;817.5380909785971;838.032072955736;911.1753862754206;913.3469569257979;29281.975130870425;41069;41574.7921260394;43453.34819977703;43595.34606980898;41950.16099678005;42182.94542636436;42216;42306.15387692244;42320.365194522106;8447.915520844805;9517.666881659143;9668.081532254424;9679.419234845896;9688.370255933378;7979.481333713319;8112.959435202816;9963.651272205474;11931.04551635867;12164.14850960433;11939.283642981449;11939.820902686466;11969.820452693217;11982.820257696141;11987.820182697224;11872.525098996073;11934.522619095225;11940.582079627215;11941.582044628483;11949.163558550905;11559.248648837798;11585.76828463426;11617.88382116185;11642.534298628045;11652.951234388946;39935.800320998496;40028.39957400641;40108;40172;40376.59623403758;3938.30914328795;4024.1348110156164;4025.416314634391;4040.1919616076902;4043.65628921542;28572.857135714396;28997.98507052264;29973.250668733457;30620;30723.539146912815;3122.9843850780826;3258.038878530839;3297.851596678155;3392.3893699134146;3404.506346579732;359878;362281;363448;363504;365705.9999999973;52975.94048119093;53159.73420132875;53323.66690832683;53366;53436.732816336444;55506.72246638741;55538;55555.44444555583;55648.93930833762;55672.72163639156;15657;15780.368785248462;15794.763078553831;15797.36810527589;15801.762973555406;11855.992240659469;13458.461661533625;13471.057026008086;13472.865271347357;13570.86429135696;11174.88251174876;11218.551257949754;13977.86022139786;14066.648333791738;14151.221682807338;5122.974385128051;5410.269613602192;6018.969905150446;6171.59884607499;6184.165137706442;895.9059298773665;950.8447236607417;967.5210770668424;992.1864071461476;1014.4319181258519;28732.13803585896;29512.557311640343;29579;30054.54918176229;30196.396072078875;970.9368891022061;983.9163671087895;1059.210887888528;1078.9298695584762;1122.927009744364;12883.355832208352;14788.92605536987;15158.772618410732;15637.218139093;15647.843521564639;522420;523606;524379;525060;526984;51989.7400512995;52153.696157596365;52187.73906130445;52207;52468.21297680538;1481.6962522682825;1484.510111663127;1502.7820965960093;1519.3011214841076;1522.2617030740082;27031.783569739804;27368.58947115755;28216.71783282141;28631.713682862905;28807.711922880502;41535.376969346085;41695.58304416917;41769;41769.582304177784;41772.79113604413;471339.643301795;474611;474627.6268618773;474916;476044;36861.44707829438;37039.44440833336;37569.43645845369;37649.62350376461;37726.62273377341;46679;47461.52538474571;49381.50618493913;49639;49945.250821238435;31934.041978740675;32509.349813003133;32849.34301314008;33261.00216993494;34316.31367372689;29912;33177.17057073592;33319.66680333165;33997.32005359929;34427.483587746705;40009.59990400175;40270;40640;40743.59256407517;40931.362745489845;30409;33202.236803310116;33913.32173356568;34772.82613586916;34775.826120870246;25472.872635637446;26370.20889373322;26413.33966650849;26622;26634.659920015903 03:39:03 INFO - PROCESS | 1916 | |2;jquery.html;404.3066141567212;442.3431204661078;444.48439809820616;445.0223956075711;454.6019745840688;21339.039743211553;22287.44281392964;22389.888050559744;23162.884185579092;23390.298291051295;23292.65061024086;23749.881250593768;24122.155724549644;24274.14803234554;24493.877530612368;5975.492083172914;6580.5393622446445;6777.796666100025;6915.827104322407;7001.684924178398;6668.166479190101;6776.762813301535;6782.287859774702;6786.151731033621;7365.153007404164;7762.4178186635945;7847.332976696987;7854.136045035041;7858.646360913744;7886.64510097047;6787.966060169717;7428.257174282556;7744.380449564022;7909.960450197712;7913.604319784045;7601.31588157063;7617.352525035379;7652.04349456318;7690.7308244211745;7693.576853273068;27734;27795.305117372027;27824.860875695693;27923.720762792316;28122.718772812215;3003.429348423814;3008.4885569453245;3078.4766589679807;3099.6590375058727;3167.904962851118;16458.506244812674;17186.74219886703;17305.480835574952;17388.043657598828;17505.912470437695;1992.860499765017;2043.1316690406518;2046.6929960505895;2051.148773259096;2064.700618410337;100884.49557752164;104821;113361;115165.12087197803;174387;17643.147469515774;20875.164993400092;20982.055807488396;21013.264535741175;21065.472143342897;33396.499052514235;33695.820646277265;34143.317133657685;34271.65728342735;34313.31373372518;10515.894841051491;10631;10836.620718274822;10853.837192442119;10988.340699558052;7545.358644515278;9035.186833185046;9045;9060.954695226614;9241.399309044891;7472.925270747223;7599.962000189964;8453.873191902127;9677;9727.881293651179;3613.6205698401814;3841.961580384216;3968.0675041365257;4084.407760874716;4091.386292056156;922.6724512797995;1058.3120971368671;1089.5206109311878;1092.6503518874042;1096.4243772019754;18442.723359149622;18802.341918032802;21065.473363165736;22093.447663808212;22553.4361640957;952.4904176265665;1034.6740776655329;1118.4463690473267;1123.1295745797072;1144.490701637763;9941.502924853728;10063.245256605784;10972.945135274273;11237.887621123848;11245.493952772145;736766;753609;758685;762734;785845;161138.38861611468;165325;168942;169729.1513542449;170977.99999999753;1203.3983008495752;1256.855461621907;1299.7205600795685;1342.6777573382317;1348.8246527951303;19326.937018463555;19859.30492432758;20355.9822008896;20841.791582084592;20933.37199884006;54166;57968.71015644895;65594.67202663956;65611.67194163999;67147.32852671607;91205;92865;93334.53332733293;95567;95642;11359.716007099889;11626.418679066182;11721.355325456841;12022.218555793845;12034.699132521408;13271.867281327062;13281.070325077151;13618.18290902549;13673.179609223478;13675;11349;11561.88438115608;11637.53449861996;11890.940545297219;11961.401929903295;9198.586063627286;9523.57143928511;10477;10588.4705764713;10615.946920265658;11570.479328430072;11784.528618855493;11811;11904.880951190376;12041.879581204426;11666.229154181105;11766.411679415824;11846.822297665714;11909.761804763682;11996.940015299868;9545.856812147684;11529;11683.824742628698;11785.410729463321;11790 03:39:03 INFO - PROCESS | 1916 | |3;mootools.html;1425.315848392772;1498.183489997951;1606.9035857848523;1644.6381796004882;1646.9588260293492;9598.328117031811;9973.800523989516;10005.749856253597;10103.242256830748;10114.44370559619;9848.556814943344;10929.726756831084;11166.609168679099;11327.490262938167;11364.545418183263;2432.5134973005424;2466.2847774145475;2469.4196863737006;2469.76537228963;2470.1231062972656;2396.979805445143;2466.9259922202273;2473.468204336067;2474.8638824864624;2474.9752502474926;2084.2600876688707;2147.4631342164457;2369.9644505332435;2441.121196369306;2457.619069044301;2448.963265551018;2451.6445115458246;2457.9877100614563;2458.729539750626;2459.7909177719825;2404.158544509408;2425.3936515871032;2431.6230984197477;2438.2807071913826;2440.255722004788;11378.260413073207;11464.71338216543;11477.827832582518;11494.770104597861;11504.482298296512;5841.6495010299095;5998.640081595075;5999.850003749898;6009.369016253274;6031.60794548353;3495.667911548399;3528.311979164064;3623.7825730455993;3719.6094410087094;3743.3636281832155;1288.3429450979975;1305.2103477396229;1879.9436016919515;1899.561793154395;1947.552063025498;15874.36502539897;15957.441489547926;16021.35914563416;16121.790865685238;16142.596435089217;4578.748168850745;4726.858194254178;4795.496472870298;4797.736124513115;4801.975990120027;11871.584494542649;12010.279383237033;12010.399480025964;12018.278903265656;12020;5034.446210916796;5858.648481091148;5861.677607731615;5870.090136028923;5881.705914704247;4570.862874113782;4861.51384861511;5315.548178404879;5366.6243362964215;5426.430224826337;3371.713404360608;3867.1105645701646;3983.6414722674517;4025.7383270087353;4035.899102522402;1041.7656027393928;1046.1682962045193;1050.5325130317087;1066.2909165405074;1066.5253961986841;946.2382781860531;961.7307153996892;964.5129209749032;983.0759086458706;997.9072915157891;6649;6724.9327506725285;6776.72893084281;6846.383825455733;6911.550749201286;955.1212884146602;996.3075662414552;999.8050380175796;1017.3285631483186;1025.2771795883884;4167.354060120686;4202.873913782591;4367.868963931087;4412.360207769855;4418.690691651554;13685.315734213249;14311.355988980313;14322.85677143236;14332.713345733235;14414.351354189086;13279.999999999807;14209.50266740659;14235.644108897153;14240.643983900278;14248;10279.794404111875;10588.364698118137;10588.364698118137;10603;10644.946775266075;5936.465718085393;6796.286389928986;6862;6918.446524278146;6926.307369263033;10694.839577406501;12242.693932651755;12489.375531223584;12549.623511294676;12580.370981451073;12732.681682958;13022.674433139247;13126.14680045794;13138.73722525525;13141.54004609834;8277.793055173428;8415.326773857954;9304.95347523258;9521.666741664008;9579;10763.569457221623;10883.074938630305;10971.890281097407;10989.285696429706;11001.669949901516;9092.772680683036;9138.543072846198;9148.771280717769;9161.633534658806;9224.354295199271;8809.295256379346;8919.42023768453;9005.729828105168;9021.503817290111;9050.818983620422;7992.760217193493;8245.711400100967;8260.173982601691;8273.255407013397;8364.996200455984;5068.265101560327;5935.762569497172;6011.969940150271;6130.693465326805;6149.692515374303;5956.612820166675;5978.701064946822;6024.548158888013;6034.818955431344;6230.968845155745 03:39:03 INFO - PROCESS | 1916 | |4;prototype.html;381.5002346925528;398.3009817769814;402.3622558245181;403.3767828704651;407.19782029402074;4163.9583604163945;4621.861344159673;4693.953060469395;4841.443234028088;5145.588352931766;4959.52023988006;5015.3730783652045;5065.366829146356;5122.6926384416965;5134.383873935133;3859.36320507116;3933.488646475961;3959.802009899508;4322.82708691652;4330.501992270898;3992.3013472642315;3994.0813612869056;4297.699161058727;4341.479022517302;4384.347469780769;3646.1613828819386;3725.459808327791;4130.97934510327;4237.626928919799;4307.363201983258;3648.5804132524836;4199.496060472732;4210.578942105765;4210.936835947463;4294.763787991659;3523.6652518010746;3649.671529562352;3910.413437984325;3918.6473217410285;3957.703172262092;3763.41667041609;4891.670871278909;4971.527704868032;5039.44566097729;5045.571126454256;4796.688215265996;4931.827386041471;4964.776585053682;4972.254161875711;4979.30289759434;5551.279103477935;5580.972095139539;5602.59935016246;5617.325920889479;5641.802536911229;4251.4685664291965;4475.865724028252;4492.955070449287;4517.299818528133;4525.646663734839;5518.365387980353;5632.3804381518;5634.295713035871;5810.564207684441;5944.286685597713;4847.636427267969;4915.21356582945;4966;4975.925361119586;5046.082488654288;4709.340692303081;4796.8081276748535;5108.795648174031;5122.771594356467;5167.68993860362;4822;4872.634552408584;5192.662476938987;5285.814996475182;5293.655912365684;4689.038747056845;4771.880702982384;5549;5602.2717046783655;5661.575381846378;1486.6357742352977;1584.9841501584835;1680.6722689075493;1754.1141723429841;1763.1448747357522;1169.4157407545845;1319.300770591588;1420.5241244183314;1430.957071287863;1436.5403071017383;4390.792532053711;4544.500104988449;4965.113256962331;5126.7180305083575;5196.870078247998;5406.782454931011;5727.11229759388;5787.768489260382;5792.565557583198;5826.212961574925;4391.3193455014525;5148.506932989391;5162.6128040397125;5172.301739265151;5201.763241805984;2465.23735529094;2583.8320509166842;2923.8245705257755;2990.7457866081627;3006.2183832203787;4910.508949105061;5116.948830511722;5274.446183150791;5662.5753068520025;5894.381089985575;5639.689817060019;5668.319801623749;5744.454276843693;5810.941890581124;5884.764609415575;5673.574481913791;6005.189299444606;6017.877753738703;6031.325722565047;6074.51403887688;4772.5227477252;5247;5428.945710543002;5524.33707955048;5702.775917392138;4631.976840115778;4781.569658730731;5009.749512524432;5390.919136212881;5544.473275038865;4840.782164802522;5746.885062298647;5791.739371728367;5863.560232982458;5868.911966320594;4490.640748740027;4564.292534657134;4588.4952655207235;4588.587027167571;4626.676132670681;4751.976240118777;5718.51392631631;5745.569082318926;5748.281464816898;5820.73806678692;4540.72755634663;4766.547178018082;5351.518363347317;5541.972290138685;5604.103343465026;4369.912601748011;4678.766061696833;5189.534970888798;5239.554637855825;5535.280413546213;5153.896922061613;5411.996342030211;5427.158790387496;5475.946520054979;5488.639884849553;4496.640268778555;5325.0148722486465;5496.395396506461;5510.641808282609;5522.392536820867;4687.39063921688;5206.140986737146;5412.702301373462;5413.40452550227;5428.267183930119 03:39:03 INFO - PROCESS | 1916 | |5;yui.html;796.4424902568204;811.2576992052273;835.1481488881337;882.9117088291172;894.1416240409209;15724.213789310532;17338.306467741306;17370.739438908426;17389.39137130201;17399.913000434997;14310.35603397847;16024.919875400607;16216.270267837946;16216.945898516587;16281.674366512663;1002.2633364477103;1018.7758693087509;1039.1063685230695;1039.537405854395;1041.182671602792;1037.678319720889;1040.2458217792077;1041.8072656558522;1041.9010194031555;1041.927065105443;1051.6056478820442;1053.062774131024;1055.208593554834;1056.2711728907068;1056.799208150449;1048.1091072588315;1050.2280823594651;1051.5478344311944;1052.9631462898801;1053.9934362683618;773.435392163718;774.44240147094;774.6901239504188;775.3448336155939;779.4037561265636;14812.185684719869;15142.621434464116;15303.464378746801;15486.457973970857;15500.612484687974;12284.078694097889;12424.689382765413;12490.830975584115;12666.80999785004;12858.935705321506;11333.603323883624;11357.659270221906;11449.942750286278;11906.107041971887;12101.394930253453;3753.418220175877;3763.1909139535132;3831.3103641344546;3847.2305538892056;3965.365541513373;90954.5452272741;120861;121423.39288303502;122089.38955305255;123445.38277308556;9713.90286097144;9769.413835169771;9932.602695892083;10091.545880435398;10230.693079207636;21836.45408864791;22235.110595575996;22329.106835726714;22338.441538961655;22436;8273.669053237923;8328.916710832935;8477.830443391098;8498.532580707997;8539.402241843012;4493.977530112328;4530.411046563926;4588.518205588432;4659.6505262105475;4663.323818046364;3488.7383446241633;3589.9948014555966;3590.4075827488696;3593.2274560969518;3593.2454184621524;575.0856138739424;602.8281939647179;617.2346290599714;621.9751209951642;625.0468036244672;661.7485355564854;698.1691786773723;725.2022774947516;734.4418242136015;735.8086897406606;10041.799164016826;10293.485325733684;10492.842607360895;10666.89333106659;10758.784824303626;614.7878981751288;674.9865002699919;733.4132693844903;737.9963100184465;738.7192866710616;4888.413390393175;4929.901401971941;4974.179260422062;4974.427940786856;4997.025580011863;84740;106607.4669626647;108574;110737;113609.4319528397;55194;57478.71260643753;92970.53514732383;94757.57863632051;95762;65478.67260663666;85136.57431712802;87126.56436717777;88589;89698.10301897029;10834.29577077519;11011.944940275247;11320.433978300887;11399.772004559696;11435.714107147387;38654.033649159;45977.540224597324;49437.50562494329;49697.75151124221;50887.98224035424;67412.97761067176;69258;69541.30458695348;69991;70431;14147.64630884195;14546.927265363605;14861.256937153315;14920.925395372953;15106.697866042838;15474.994125381814;15823.841761582236;16229.59426014359;16231.837681623032;16922.746158807382;13986.580402587939;14536.636584085483;14718.601732835363;14949.551513454613;15049.849501504845;13898.930505347813;14014;14899.627509312353;15006.02460840042;15370.538883833502;13330.866876315622;14638.121712697272;14661.48684796027;14811.25943702789;14983.47557835514;10044.648437304952;10242.897571024194;10419.95800419952;10478;10578.89421105779;11025.44872756375;11331.320120792449;11631.243969141979;11863.58477453285;12056.156069075081 03:39:03 INFO - PROCESS | 1916 | __end_tp_report 03:39:03 INFO - PROCESS | 1916 | __start_cc_report 03:39:03 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,1611 03:39:03 INFO - PROCESS | 1916 | __end_cc_report 03:39:03 INFO - PROCESS | 1916 | __startTimestamp1496399943353__endTimestamp 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | ------- Summary: start ------- 03:39:03 INFO - PROCESS | 1916 | Number of tests: 6 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:6125.43 Median:6317.75 stddev:684.44 (10.8%) stddev-sans-first:656.28 03:39:03 INFO - PROCESS | 1916 | Values: 3560.1 3561.6 3579.7 3621.1 3626.9 6154.9 6160.5 6171.3 6175.4 6176.5 6107.1 6174.4 6178.4 6179.7 6184.0 5533.4 5552.8 5594.5 5628.6 5727.0 5478.4 5484.9 5491.5 5718.0 5727.7 5587.6 5593.7 5595.1 5599.5 5605.8 5576.6 5583.7 5586.2 5594.0 5600.8 4693.4 5378.2 5553.5 5554.4 5557.8 6191.1 6209.0 6212.4 6213.3 6218.6 6357.6 6500.4 6534.1 6544.5 6547.0 6676.2 6686.7 6688.0 6690.6 6693.3 6221.2 6231.5 6250.3 6261.7 6315.1 6545.4 6602.9 6674.1 6717.8 6761.9 6491.7 6498.7 6510.3 6511.6 6524.0 6657.7 6664.5 6666.7 6673.7 6679.1 6144.2 6380.4 6574.3 6582.9 6591.0 6454.7 6456.0 6556.6 6567.6 6580.0 5317.7 5336.1 5339.1 5341.4 5367.9 4639.7 4684.8 4735.4 4736.9 4745.2 6193.5 6200.4 6200.6 6258.0 6263.4 6674.4 6676.2 6678.2 6680.7 6684.6 6296.3 6304.6 6317.5 6317.7 6337.8 5638.7 5661.7 5718.4 5758.9 5788.0 6841.6 6884.2 6923.8 6928.5 6940.3 6583.9 6631.9 6652.1 6656.6 6668.2 6609.1 6656.8 6895.6 6918.9 6925.9 6462.7 6512.3 6534.7 6576.1 6578.7 6660.3 6665.9 6669.2 6673.0 6673.4 6221.6 6335.6 6461.3 6474.5 6489.7 6278.0 6350.1 6400.2 6471.1 6473.7 6348.8 6360.6 6361.9 6365.7 6377.9 4919.0 6255.0 6270.0 6336.1 6466.2 6275.2 6296.4 6298.0 6335.0 6337.6 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:59868.09 Median:364605.00 stddev:121857.46 (33.4%) stddev-sans-first:122118.53 03:39:03 INFO - PROCESS | 1916 | Values: 774.0 817.5 838.0 911.2 913.3 29282.0 41069.0 41574.8 43453.3 43595.3 41950.2 42182.9 42216.0 42306.2 42320.4 8447.9 9517.7 9668.1 9679.4 9688.4 7979.5 8113.0 9963.7 11931.0 12164.1 11939.3 11939.8 11969.8 11982.8 11987.8 11872.5 11934.5 11940.6 11941.6 11949.2 11559.2 11585.8 11617.9 11642.5 11653.0 39935.8 40028.4 40108.0 40172.0 40376.6 3938.3 4024.1 4025.4 4040.2 4043.7 28572.9 28998.0 29973.3 30620.0 30723.5 3123.0 3258.0 3297.9 3392.4 3404.5 359878.0 362281.0 363448.0 363504.0 365706.0 52975.9 53159.7 53323.7 53366.0 53436.7 55506.7 55538.0 55555.4 55648.9 55672.7 15657.0 15780.4 15794.8 15797.4 15801.8 11856.0 13458.5 13471.1 13472.9 13570.9 11174.9 11218.6 13977.9 14066.6 14151.2 5123.0 5410.3 6019.0 6171.6 6184.2 895.9 950.8 967.5 992.2 1014.4 28732.1 29512.6 29579.0 30054.5 30196.4 970.9 983.9 1059.2 1078.9 1122.9 12883.4 14788.9 15158.8 15637.2 15647.8 522420.0 523606.0 524379.0 525060.0 526984.0 51989.7 52153.7 52187.7 52207.0 52468.2 1481.7 1484.5 1502.8 1519.3 1522.3 27031.8 27368.6 28216.7 28631.7 28807.7 41535.4 41695.6 41769.0 41769.6 41772.8 471339.6 474611.0 474627.6 474916.0 476044.0 36861.4 37039.4 37569.4 37649.6 37726.6 46679.0 47461.5 49381.5 49639.0 49945.3 31934.0 32509.3 32849.3 33261.0 34316.3 29912.0 33177.2 33319.7 33997.3 34427.5 40009.6 40270.0 40640.0 40743.6 40931.4 30409.0 33202.2 33913.3 34772.8 34775.8 25472.9 26370.2 26413.3 26622.0 26634.7 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:43367.06 Median:22471.66 stddev:126383.34 (562.4%) stddev-sans-first:126696.71 03:39:03 INFO - PROCESS | 1916 | Values: 404.3 442.3 444.5 445.0 454.6 21339.0 22287.4 22389.9 23162.9 23390.3 23292.7 23749.9 24122.2 24274.1 24493.9 5975.5 6580.5 6777.8 6915.8 7001.7 6668.2 6776.8 6782.3 6786.2 7365.2 7762.4 7847.3 7854.1 7858.6 7886.6 6788.0 7428.3 7744.4 7910.0 7913.6 7601.3 7617.4 7652.0 7690.7 7693.6 27734.0 27795.3 27824.9 27923.7 28122.7 3003.4 3008.5 3078.5 3099.7 3167.9 16458.5 17186.7 17305.5 17388.0 17505.9 1992.9 2043.1 2046.7 2051.1 2064.7 100884.5 104821.0 113361.0 115165.1 174387.0 17643.1 20875.2 20982.1 21013.3 21065.5 33396.5 33695.8 34143.3 34271.7 34313.3 10515.9 10631.0 10836.6 10853.8 10988.3 7545.4 9035.2 9045.0 9061.0 9241.4 7472.9 7600.0 8453.9 9677.0 9727.9 3613.6 3842.0 3968.1 4084.4 4091.4 922.7 1058.3 1089.5 1092.7 1096.4 18442.7 18802.3 21065.5 22093.4 22553.4 952.5 1034.7 1118.4 1123.1 1144.5 9941.5 10063.2 10972.9 11237.9 11245.5 736766.0 753609.0 758685.0 762734.0 785845.0 161138.4 165325.0 168942.0 169729.2 170978.0 1203.4 1256.9 1299.7 1342.7 1348.8 19326.9 19859.3 20356.0 20841.8 20933.4 54166.0 57968.7 65594.7 65611.7 67147.3 91205.0 92865.0 93334.5 95567.0 95642.0 11359.7 11626.4 11721.4 12022.2 12034.7 13271.9 13281.1 13618.2 13673.2 13675.0 11349.0 11561.9 11637.5 11890.9 11961.4 9198.6 9523.6 10477.0 10588.5 10615.9 11570.5 11784.5 11811.0 11904.9 12041.9 11666.2 11766.4 11846.8 11909.8 11996.9 9545.9 11529.0 11683.8 11785.4 11790.0 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:6878.66 Median:2471.80 stddev:4329.80 (175.2%) stddev-sans-first:4322.56 03:39:03 INFO - PROCESS | 1916 | Values: 1425.3 1498.2 1606.9 1644.6 1647.0 9598.3 9973.8 10005.7 10103.2 10114.4 9848.6 10929.7 11166.6 11327.5 11364.5 2432.5 2466.3 2469.4 2469.8 2470.1 2397.0 2466.9 2473.5 2474.9 2475.0 2084.3 2147.5 2370.0 2441.1 2457.6 2449.0 2451.6 2458.0 2458.7 2459.8 2404.2 2425.4 2431.6 2438.3 2440.3 11378.3 11464.7 11477.8 11494.8 11504.5 5841.6 5998.6 5999.9 6009.4 6031.6 3495.7 3528.3 3623.8 3719.6 3743.4 1288.3 1305.2 1879.9 1899.6 1947.6 15874.4 15957.4 16021.4 16121.8 16142.6 4578.7 4726.9 4795.5 4797.7 4802.0 11871.6 12010.3 12010.4 12018.3 12020.0 5034.4 5858.6 5861.7 5870.1 5881.7 4570.9 4861.5 5315.5 5366.6 5426.4 3371.7 3867.1 3983.6 4025.7 4035.9 1041.8 1046.2 1050.5 1066.3 1066.5 946.2 961.7 964.5 983.1 997.9 6649.0 6724.9 6776.7 6846.4 6911.6 955.1 996.3 999.8 1017.3 1025.3 4167.4 4202.9 4367.9 4412.4 4418.7 13685.3 14311.4 14322.9 14332.7 14414.4 13280.0 14209.5 14235.6 14240.6 14248.0 10279.8 10588.4 10588.4 10603.0 10644.9 5936.5 6796.3 6862.0 6918.4 6926.3 10694.8 12242.7 12489.4 12549.6 12580.4 12732.7 13022.7 13126.1 13138.7 13141.5 8277.8 8415.3 9305.0 9521.7 9579.0 10763.6 10883.1 10971.9 10989.3 11001.7 9092.8 9138.5 9148.8 9161.6 9224.4 8809.3 8919.4 9005.7 9021.5 9050.8 7992.8 8245.7 8260.2 8273.3 8365.0 5068.3 5935.8 6012.0 6130.7 6149.7 5956.6 5978.7 6024.5 6034.8 6231.0 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4631.26 Median:4971.89 stddev:1269.33 (25.5%) stddev-sans-first:1232.17 03:39:03 INFO - PROCESS | 1916 | Values: 381.5 398.3 402.4 403.4 407.2 4164.0 4621.9 4694.0 4841.4 5145.6 4959.5 5015.4 5065.4 5122.7 5134.4 3859.4 3933.5 3959.8 4322.8 4330.5 3992.3 3994.1 4297.7 4341.5 4384.3 3646.2 3725.5 4131.0 4237.6 4307.4 3648.6 4199.5 4210.6 4210.9 4294.8 3523.7 3649.7 3910.4 3918.6 3957.7 3763.4 4891.7 4971.5 5039.4 5045.6 4796.7 4931.8 4964.8 4972.3 4979.3 5551.3 5581.0 5602.6 5617.3 5641.8 4251.5 4475.9 4493.0 4517.3 4525.6 5518.4 5632.4 5634.3 5810.6 5944.3 4847.6 4915.2 4966.0 4975.9 5046.1 4709.3 4796.8 5108.8 5122.8 5167.7 4822.0 4872.6 5192.7 5285.8 5293.7 4689.0 4771.9 5549.0 5602.3 5661.6 1486.6 1585.0 1680.7 1754.1 1763.1 1169.4 1319.3 1420.5 1431.0 1436.5 4390.8 4544.5 4965.1 5126.7 5196.9 5406.8 5727.1 5787.8 5792.6 5826.2 4391.3 5148.5 5162.6 5172.3 5201.8 2465.2 2583.8 2923.8 2990.7 3006.2 4910.5 5116.9 5274.4 5662.6 5894.4 5639.7 5668.3 5744.5 5810.9 5884.8 5673.6 6005.2 6017.9 6031.3 6074.5 4772.5 5247.0 5428.9 5524.3 5702.8 4632.0 4781.6 5009.7 5390.9 5544.5 4840.8 5746.9 5791.7 5863.6 5868.9 4490.6 4564.3 4588.5 4588.6 4626.7 4752.0 5718.5 5745.6 5748.3 5820.7 4540.7 4766.5 5351.5 5542.0 5604.1 4369.9 4678.8 5189.5 5239.6 5535.3 5153.9 5412.0 5427.2 5475.9 5488.6 4496.6 5325.0 5496.4 5510.6 5522.4 4687.4 5206.1 5412.7 5413.4 5428.3 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:20976.56 Median:16216.61 stddev:30305.99 (186.9%) stddev-sans-first:30353.12 03:39:03 INFO - PROCESS | 1916 | Values: 796.4 811.3 835.1 882.9 894.1 15724.2 17338.3 17370.7 17389.4 17399.9 14310.4 16024.9 16216.3 16216.9 16281.7 1002.3 1018.8 1039.1 1039.5 1041.2 1037.7 1040.2 1041.8 1041.9 1041.9 1051.6 1053.1 1055.2 1056.3 1056.8 1048.1 1050.2 1051.5 1053.0 1054.0 773.4 774.4 774.7 775.3 779.4 14812.2 15142.6 15303.5 15486.5 15500.6 12284.1 12424.7 12490.8 12666.8 12858.9 11333.6 11357.7 11449.9 11906.1 12101.4 3753.4 3763.2 3831.3 3847.2 3965.4 90954.5 120861.0 121423.4 122089.4 123445.4 9713.9 9769.4 9932.6 10091.5 10230.7 21836.5 22235.1 22329.1 22338.4 22436.0 8273.7 8328.9 8477.8 8498.5 8539.4 4494.0 4530.4 4588.5 4659.7 4663.3 3488.7 3590.0 3590.4 3593.2 3593.2 575.1 602.8 617.2 622.0 625.0 661.7 698.2 725.2 734.4 735.8 10041.8 10293.5 10492.8 10666.9 10758.8 614.8 675.0 733.4 738.0 738.7 4888.4 4929.9 4974.2 4974.4 4997.0 84740.0 106607.5 108574.0 110737.0 113609.4 55194.0 57478.7 92970.5 94757.6 95762.0 65478.7 85136.6 87126.6 88589.0 89698.1 10834.3 11011.9 11320.4 11399.8 11435.7 38654.0 45977.5 49437.5 49697.8 50888.0 67413.0 69258.0 69541.3 69991.0 70431.0 14147.6 14546.9 14861.3 14920.9 15106.7 15475.0 15823.8 16229.6 16231.8 16922.7 13986.6 14536.6 14718.6 14949.6 15049.8 13898.9 14014.0 14899.6 15006.0 15370.5 13330.9 14638.1 14661.5 14811.3 14983.5 10044.6 10242.9 10420.0 10478.0 10578.9 11025.4 11331.3 11631.2 11863.6 12056.2 03:39:03 INFO - PROCESS | 1916 | -------- Summary: end -------- 03:39:03 INFO - PROCESS | 1916 | 03:39:03 INFO - TEST-INFO | 1916: exit 0 03:39:03 INFO - TEST-OK | dromaeo_css | took 1119061ms 03:39:03 INFO - TEST-START | kraken 03:39:03 INFO - operating with platform_type : mac_ 03:39:03 INFO - Initialising browser for kraken test... 03:39:03 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpZKcYsU/profile http://localhost:49347/getInfo.html) 03:39:06 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 03:39:06 INFO - PROCESS | 1944 | colorDepth:24 03:39:06 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 03:39:06 INFO - PROCESS | 1944 | __metrics 03:39:06 INFO - PROCESS | 1944 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:39:06 INFO - PROCESS | 1944 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:39:07 INFO - TEST-INFO | 1944: exit 0 03:39:07 INFO - Browser initialized. 03:39:07 INFO - Running cycle 1/1 for kraken test... 03:39:07 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpZKcYsU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:39:19 INFO - PROCESS | 1946 | RSS: Main: 169312256 03:39:19 INFO - PROCESS | 1946 | 03:39:21 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-beat-detection) 03:39:22 INFO - PROCESS | 1946 | RSS: Main: 219656192 03:39:22 INFO - PROCESS | 1946 | 03:39:25 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-dft) 03:39:25 INFO - PROCESS | 1946 | RSS: Main: 229355520 03:39:25 INFO - PROCESS | 1946 | 03:39:29 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-fft) 03:39:29 INFO - PROCESS | 1946 | RSS: Main: 326688768 03:39:29 INFO - PROCESS | 1946 | 03:39:31 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-oscillator) 03:39:32 INFO - PROCESS | 1946 | RSS: Main: 229236736 03:39:32 INFO - PROCESS | 1946 | 03:39:34 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:39:34 INFO - PROCESS | 1946 | RSS: Main: 231059456 03:39:34 INFO - PROCESS | 1946 | 03:39:37 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-darkroom) 03:39:38 INFO - PROCESS | 1946 | RSS: Main: 271310848 03:39:38 INFO - PROCESS | 1946 | 03:39:41 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-desaturate) 03:39:42 INFO - PROCESS | 1946 | RSS: Main: 273485824 03:39:42 INFO - PROCESS | 1946 | 03:39:44 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49347/tests/kraken/driver.html?testName=json-parse-financial) 03:39:45 INFO - PROCESS | 1946 | RSS: Main: 268001280 03:39:45 INFO - PROCESS | 1946 | 03:39:47 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49347/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:39:47 INFO - PROCESS | 1946 | RSS: Main: 249630720 03:39:47 INFO - PROCESS | 1946 | 03:39:49 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:39:50 INFO - PROCESS | 1946 | RSS: Main: 252481536 03:39:50 INFO - PROCESS | 1946 | 03:39:52 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:39:53 INFO - PROCESS | 1946 | RSS: Main: 261742592 03:39:53 INFO - PROCESS | 1946 | 03:39:55 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:39:56 INFO - PROCESS | 1946 | RSS: Main: 273629184 03:39:56 INFO - PROCESS | 1946 | 03:39:59 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:40:00 INFO - PROCESS | 1946 | RSS: Main: 267599872 03:40:00 INFO - PROCESS | 1946 | 03:40:01 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49347/tests/kraken/driver.html?testName=ai-astar) 03:40:02 INFO - PROCESS | 1946 | RSS: Main: 312274944 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | __start_tp_report 03:40:02 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 03:40:02 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 03:40:02 INFO - PROCESS | 1946 | |i|pagename|runs| 03:40:02 INFO - PROCESS | 1946 | |0;ai-astar;117;115;114;114;114;114;114;113;113;114 03:40:02 INFO - PROCESS | 1946 | |1;audio-beat-detection;140;109;111;160;133;109;109;107;127;106 03:40:02 INFO - PROCESS | 1946 | |2;audio-dft;186;183;185;183;183;185;185;183;187;184 03:40:02 INFO - PROCESS | 1946 | |3;audio-fft;83;59;61;96;88;78;59;51;76;51 03:40:02 INFO - PROCESS | 1946 | |4;audio-oscillator;89;69;86;69;68;69;81;69;69;82 03:40:02 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;119;86;87;86;89;133;87;86;87;86 03:40:02 INFO - PROCESS | 1946 | |6;imaging-darkroom;92;93;100;143;94;93;92;95;93;93 03:40:02 INFO - PROCESS | 1946 | |7;imaging-desaturate;83;87;86;86;83;84;84;84;82;82 03:40:02 INFO - PROCESS | 1946 | |8;json-parse-financial;61;58;58;59;62;61;62;63;62;62 03:40:02 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;55;64;60;68;62;59;59;59;58;58 03:40:02 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;83;83;80;84;80;82;80;85;86;80 03:40:02 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;136;137;134;133;145;132;135;138;176;151 03:40:02 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;161;173;205;188;164;162;165;163;164;164 03:40:02 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;65;65;66;65;64;64;63;63;63;63 03:40:02 INFO - PROCESS | 1946 | __end_tp_report 03:40:02 INFO - PROCESS | 1946 | __start_cc_report 03:40:02 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,2148 03:40:02 INFO - PROCESS | 1946 | __end_cc_report 03:40:02 INFO - PROCESS | 1946 | __startTimestamp1496400002174__endTimestamp 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | ------- Summary: start ------- 03:40:02 INFO - PROCESS | 1946 | Number of tests: 14 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:1.14 (1.0%) stddev-sans-first:0.60 03:40:02 INFO - PROCESS | 1946 | Values: 117.0 115.0 114.0 114.0 114.0 114.0 114.0 113.0 113.0 114.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:121.10 Median:119.00 stddev:18.30 (15.4%) stddev-sans-first:18.09 03:40:02 INFO - PROCESS | 1946 | Values: 140.0 109.0 111.0 160.0 133.0 109.0 109.0 107.0 127.0 106.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.43 (0.8%) stddev-sans-first:1.39 03:40:02 INFO - PROCESS | 1946 | Values: 186.0 183.0 185.0 183.0 183.0 185.0 185.0 183.0 187.0 184.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:70.20 Median:77.00 stddev:16.03 (20.8%) stddev-sans-first:16.32 03:40:02 INFO - PROCESS | 1946 | Values: 83.0 59.0 61.0 96.0 88.0 78.0 59.0 51.0 76.0 51.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:75.00 stddev:8.37 (11.2%) stddev-sans-first:7.21 03:40:02 INFO - PROCESS | 1946 | Values: 89.0 69.0 86.0 69.0 68.0 69.0 81.0 69.0 69.0 82.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:86.50 stddev:16.90 (19.5%) stddev-sans-first:15.45 03:40:02 INFO - PROCESS | 1946 | Values: 119.0 86.0 87.0 86.0 89.0 133.0 87.0 86.0 87.0 86.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:98.80 Median:93.00 stddev:15.70 (16.9%) stddev-sans-first:16.46 03:40:02 INFO - PROCESS | 1946 | Values: 92.0 93.0 100.0 143.0 94.0 93.0 92.0 95.0 93.0 93.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:1.73 (2.1%) stddev-sans-first:1.79 03:40:02 INFO - PROCESS | 1946 | Values: 83.0 87.0 86.0 86.0 83.0 84.0 84.0 84.0 82.0 82.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:62.00 stddev:1.81 (2.9%) stddev-sans-first:1.92 03:40:02 INFO - PROCESS | 1946 | Values: 61.0 58.0 58.0 59.0 62.0 61.0 62.0 63.0 62.0 62.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:59.50 stddev:3.65 (6.1%) stddev-sans-first:3.35 03:40:02 INFO - PROCESS | 1946 | Values: 55.0 64.0 60.0 68.0 62.0 59.0 59.0 59.0 58.0 58.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:2.26 (2.7%) stddev-sans-first:2.39 03:40:02 INFO - PROCESS | 1946 | Values: 83.0 83.0 80.0 84.0 80.0 82.0 80.0 85.0 86.0 80.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:137.50 stddev:13.40 (9.7%) stddev-sans-first:14.05 03:40:02 INFO - PROCESS | 1946 | Values: 136.0 137.0 134.0 133.0 145.0 132.0 135.0 138.0 176.0 151.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:164.50 stddev:14.44 (8.8%) stddev-sans-first:14.87 03:40:02 INFO - PROCESS | 1946 | Values: 161.0 173.0 205.0 188.0 164.0 162.0 165.0 163.0 164.0 164.0 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.50 stddev:1.10 (1.7%) stddev-sans-first:1.12 03:40:02 INFO - PROCESS | 1946 | Values: 65.0 65.0 66.0 65.0 64.0 64.0 63.0 63.0 63.0 63.0 03:40:02 INFO - PROCESS | 1946 | -------- Summary: end -------- 03:40:02 INFO - PROCESS | 1946 | 03:40:02 INFO - TEST-INFO | 1946: exit 0 03:40:03 INFO - TEST-OK | kraken | took 59076ms 03:40:03 INFO - SUITE-END | took 1178s 03:40:03 INFO - Completed test suite (00:19:38) 03:40:03 INFO - javascript benchmark 03:40:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7963.323881750811, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3560.1455650643843, 3561.608223095459, 3579.7315201359906, 3621.130928577141, 3626.87305944292, 6154.938450615493, 6160.537959653033, 6171.259448866137, 6175.351588083248, 6176.53675974302, 6107.1449997000345, 6174.444300012998, 6178.382161783819, 6179.721912513936, 6184.0, 5533.418991005947, 5552.77788888444, 5594.524465420444, 5628.605997580171, 5727.0, 5478.369987451434, 5484.91772623411, 5491.533219676332, 5717.971410142944, 5727.684977326246, 5587.636803607773, 5593.720313984305, 5595.132754423071, 5599.468050535193, 5605.775768969236, 5576.553875689935, 5583.748731307101, 5586.24585680932, 5594.049011668006, 5600.831975040755, 4693.366395536627, 5378.220158077096, 5553.527950124244, 5554.416786237468, 5557.833265002057, 6191.0713392990965, 6208.9689551551955, 6212.378762123797, 6213.254409470847, 6218.595791273553, 6357.618542887441, 6500.4474619657385, 6534.052562378429, 6544.509161812883, 6546.967265163691, 6676.198856137246, 6686.6656667166935, 6688.0, 6690.565113267622, 6693.297203793629, 6221.191245138149, 6231.501479881598, 6250.312465628777, 6261.655608941552, 6315.115883776275, 6545.37818907203, 6602.900956485657, 6674.098996635489, 6717.832054198587, 6761.864762704817, 6491.740330386826, 6498.675066246668, 6510.251321098134, 6511.609303441809, 6524.0, 6657.700403481855, 6664.5334826561675, 6666.699998500079, 6673.666316684244, 6679.131712877392, 6144.218176728698, 6380.425761681471, 6574.342565743483, 6582.868342633121, 6591.0, 6454.709538070798, 6455.967720161463, 6556.63938483388, 6567.638779867153, 6579.967100164565, 5317.654352467077, 5336.1195402758885, 5339.11904535755, 5341.359036915595, 5367.89958058597, 4639.698419602715, 4684.812607495662, 4735.408073990751, 4736.881577960578, 4745.193317136026, 6193.504519638418, 6200.3799620037635, 6200.56596038282, 6257.968710156511, 6263.4362907337645, 6674.432673222734, 6676.165479315086, 6678.23200331968, 6680.665966701743, 6684.63234522106, 6296.275928268308, 6304.621722696653, 6317.4946004319545, 6317.747290108344, 6337.7781777637565, 5638.718064096861, 5661.745221465044, 5718.370979192286, 5758.884822303614, 5787.971060144672, 6841.623710696059, 6884.242733299433, 6923.792286231421, 6928.549644273106, 6940.340667636567, 6583.934160658524, 6631.900521492085, 6652.101966234496, 6656.633885136364, 6668.233153187251, 6609.107770450928, 6656.7670131545165, 6895.620740859301, 6918.930810691829, 6925.861482770417, 6462.741490340334, 6512.283648798723, 6534.738610455529, 6576.112224849584, 6578.736850525926, 6660.333966603494, 6665.866682666417, 6669.233038200669, 6673.032410300576, 6673.432758215606, 6221.564490485623, 6335.588186767845, 6461.289258181502, 6474.514411419068, 6489.7404103835315, 6277.96861015692, 6350.079238510298, 6400.199975003125, 6471.094046833353, 6473.676316184266, 6348.77779277723, 6360.618362898241, 6361.93638063632, 6365.745370185141, 6377.8724425512155, 4919.0, 6255.0, 6269.96865015672, 6336.112944187909, 6466.159399278064, 6275.215598050244, 6296.370362963667, 6297.96851015742, 6335.0, 6337.588056776295], "value": 6081.619759720003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.9651715672794, 817.5380909785971, 838.032072955736, 911.1753862754206, 913.3469569257979, 29281.975130870425, 41069.0, 41574.7921260394, 43453.34819977703, 43595.34606980898, 41950.16099678005, 42182.94542636436, 42216.0, 42306.15387692244, 42320.365194522106, 8447.915520844805, 9517.666881659143, 9668.081532254424, 9679.419234845896, 9688.370255933378, 7979.481333713319, 8112.959435202816, 9963.651272205474, 11931.04551635867, 12164.14850960433, 11939.283642981449, 11939.820902686466, 11969.820452693217, 11982.820257696141, 11987.820182697224, 11872.525098996073, 11934.522619095225, 11940.582079627215, 11941.582044628483, 11949.163558550905, 11559.248648837798, 11585.76828463426, 11617.88382116185, 11642.534298628045, 11652.951234388946, 39935.800320998496, 40028.39957400641, 40108.0, 40172.0, 40376.59623403758, 3938.30914328795, 4024.1348110156164, 4025.416314634391, 4040.1919616076902, 4043.65628921542, 28572.857135714396, 28997.98507052264, 29973.250668733457, 30620.0, 30723.539146912815, 3122.9843850780826, 3258.038878530839, 3297.851596678155, 3392.3893699134146, 3404.506346579732, 359878.0, 362281.0, 363448.0, 363504.0, 365705.9999999973, 52975.94048119093, 53159.73420132875, 53323.66690832683, 53366.0, 53436.732816336444, 55506.72246638741, 55538.0, 55555.44444555583, 55648.93930833762, 55672.72163639156, 15657.0, 15780.368785248462, 15794.763078553831, 15797.36810527589, 15801.762973555406, 11855.992240659469, 13458.461661533625, 13471.057026008086, 13472.865271347357, 13570.86429135696, 11174.88251174876, 11218.551257949754, 13977.86022139786, 14066.648333791738, 14151.221682807338, 5122.974385128051, 5410.269613602192, 6018.969905150446, 6171.59884607499, 6184.165137706442, 895.9059298773665, 950.8447236607417, 967.5210770668424, 992.1864071461476, 1014.4319181258519, 28732.13803585896, 29512.557311640343, 29579.0, 30054.54918176229, 30196.396072078875, 970.9368891022061, 983.9163671087895, 1059.210887888528, 1078.9298695584762, 1122.927009744364, 12883.355832208352, 14788.92605536987, 15158.772618410732, 15637.218139093, 15647.843521564639, 522420.0, 523606.0, 524379.0, 525060.0, 526984.0, 51989.7400512995, 52153.696157596365, 52187.73906130445, 52207.0, 52468.21297680538, 1481.6962522682825, 1484.510111663127, 1502.7820965960093, 1519.3011214841076, 1522.2617030740082, 27031.783569739804, 27368.58947115755, 28216.71783282141, 28631.713682862905, 28807.711922880502, 41535.376969346085, 41695.58304416917, 41769.0, 41769.582304177784, 41772.79113604413, 471339.643301795, 474611.0, 474627.6268618773, 474916.0, 476044.0, 36861.44707829438, 37039.44440833336, 37569.43645845369, 37649.62350376461, 37726.62273377341, 46679.0, 47461.52538474571, 49381.50618493913, 49639.0, 49945.250821238435, 31934.041978740675, 32509.349813003133, 32849.34301314008, 33261.00216993494, 34316.31367372689, 29912.0, 33177.17057073592, 33319.66680333165, 33997.32005359929, 34427.483587746705, 40009.59990400175, 40270.0, 40640.0, 40743.59256407517, 40931.362745489845, 30409.0, 33202.236803310116, 33913.32173356568, 34772.82613586916, 34775.826120870246, 25472.872635637446, 26370.20889373322, 26413.33966650849, 26622.0, 26634.659920015903], "value": 19725.05834063546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.3066141567212, 442.3431204661078, 444.48439809820616, 445.0223956075711, 454.6019745840688, 21339.039743211553, 22287.44281392964, 22389.888050559744, 23162.884185579092, 23390.298291051295, 23292.65061024086, 23749.881250593768, 24122.155724549644, 24274.14803234554, 24493.877530612368, 5975.492083172914, 6580.5393622446445, 6777.796666100025, 6915.827104322407, 7001.684924178398, 6668.166479190101, 6776.762813301535, 6782.287859774702, 6786.151731033621, 7365.153007404164, 7762.4178186635945, 7847.332976696987, 7854.136045035041, 7858.646360913744, 7886.64510097047, 6787.966060169717, 7428.257174282556, 7744.380449564022, 7909.960450197712, 7913.604319784045, 7601.31588157063, 7617.352525035379, 7652.04349456318, 7690.7308244211745, 7693.576853273068, 27734.0, 27795.305117372027, 27824.860875695693, 27923.720762792316, 28122.718772812215, 3003.429348423814, 3008.4885569453245, 3078.4766589679807, 3099.6590375058727, 3167.904962851118, 16458.506244812674, 17186.74219886703, 17305.480835574952, 17388.043657598828, 17505.912470437695, 1992.860499765017, 2043.1316690406518, 2046.6929960505895, 2051.148773259096, 2064.700618410337, 100884.49557752164, 104821.0, 113361.0, 115165.12087197803, 174387.0, 17643.147469515774, 20875.164993400092, 20982.055807488396, 21013.264535741175, 21065.472143342897, 33396.499052514235, 33695.820646277265, 34143.317133657685, 34271.65728342735, 34313.31373372518, 10515.894841051491, 10631.0, 10836.620718274822, 10853.837192442119, 10988.340699558052, 7545.358644515278, 9035.186833185046, 9045.0, 9060.954695226614, 9241.399309044891, 7472.925270747223, 7599.962000189964, 8453.873191902127, 9677.0, 9727.881293651179, 3613.6205698401814, 3841.961580384216, 3968.0675041365257, 4084.407760874716, 4091.386292056156, 922.6724512797995, 1058.3120971368671, 1089.5206109311878, 1092.6503518874042, 1096.4243772019754, 18442.723359149622, 18802.341918032802, 21065.473363165736, 22093.447663808212, 22553.4361640957, 952.4904176265665, 1034.6740776655329, 1118.4463690473267, 1123.1295745797072, 1144.490701637763, 9941.502924853728, 10063.245256605784, 10972.945135274273, 11237.887621123848, 11245.493952772145, 736766.0, 753609.0, 758685.0, 762734.0, 785845.0, 161138.38861611468, 165325.0, 168942.0, 169729.1513542449, 170977.99999999753, 1203.3983008495752, 1256.855461621907, 1299.7205600795685, 1342.6777573382317, 1348.8246527951303, 19326.937018463555, 19859.30492432758, 20355.9822008896, 20841.791582084592, 20933.37199884006, 54166.0, 57968.71015644895, 65594.67202663956, 65611.67194163999, 67147.32852671607, 91205.0, 92865.0, 93334.53332733293, 95567.0, 95642.0, 11359.716007099889, 11626.418679066182, 11721.355325456841, 12022.218555793845, 12034.699132521408, 13271.867281327062, 13281.070325077151, 13618.18290902549, 13673.179609223478, 13675.0, 11349.0, 11561.88438115608, 11637.53449861996, 11890.940545297219, 11961.401929903295, 9198.586063627286, 9523.57143928511, 10477.0, 10588.4705764713, 10615.946920265658, 11570.479328430072, 11784.528618855493, 11811.0, 11904.880951190376, 12041.879581204426, 11666.229154181105, 11766.411679415824, 11846.822297665714, 11909.761804763682, 11996.940015299868, 9545.856812147684, 11529.0, 11683.824742628698, 11785.410729463321, 11790.0], "value": 11921.890812658514, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.315848392772, 1498.183489997951, 1606.9035857848523, 1644.6381796004882, 1646.9588260293492, 9598.328117031811, 9973.800523989516, 10005.749856253597, 10103.242256830748, 10114.44370559619, 9848.556814943344, 10929.726756831084, 11166.609168679099, 11327.490262938167, 11364.545418183263, 2432.5134973005424, 2466.2847774145475, 2469.4196863737006, 2469.76537228963, 2470.1231062972656, 2396.979805445143, 2466.9259922202273, 2473.468204336067, 2474.8638824864624, 2474.9752502474926, 2084.2600876688707, 2147.4631342164457, 2369.9644505332435, 2441.121196369306, 2457.619069044301, 2448.963265551018, 2451.6445115458246, 2457.9877100614563, 2458.729539750626, 2459.7909177719825, 2404.158544509408, 2425.3936515871032, 2431.6230984197477, 2438.2807071913826, 2440.255722004788, 11378.260413073207, 11464.71338216543, 11477.827832582518, 11494.770104597861, 11504.482298296512, 5841.6495010299095, 5998.640081595075, 5999.850003749898, 6009.369016253274, 6031.60794548353, 3495.667911548399, 3528.311979164064, 3623.7825730455993, 3719.6094410087094, 3743.3636281832155, 1288.3429450979975, 1305.2103477396229, 1879.9436016919515, 1899.561793154395, 1947.552063025498, 15874.36502539897, 15957.441489547926, 16021.35914563416, 16121.790865685238, 16142.596435089217, 4578.748168850745, 4726.858194254178, 4795.496472870298, 4797.736124513115, 4801.975990120027, 11871.584494542649, 12010.279383237033, 12010.399480025964, 12018.278903265656, 12020.0, 5034.446210916796, 5858.648481091148, 5861.677607731615, 5870.090136028923, 5881.705914704247, 4570.862874113782, 4861.51384861511, 5315.548178404879, 5366.6243362964215, 5426.430224826337, 3371.713404360608, 3867.1105645701646, 3983.6414722674517, 4025.7383270087353, 4035.899102522402, 1041.7656027393928, 1046.1682962045193, 1050.5325130317087, 1066.2909165405074, 1066.5253961986841, 946.2382781860531, 961.7307153996892, 964.5129209749032, 983.0759086458706, 997.9072915157891, 6649.0, 6724.9327506725285, 6776.72893084281, 6846.383825455733, 6911.550749201286, 955.1212884146602, 996.3075662414552, 999.8050380175796, 1017.3285631483186, 1025.2771795883884, 4167.354060120686, 4202.873913782591, 4367.868963931087, 4412.360207769855, 4418.690691651554, 13685.315734213249, 14311.355988980313, 14322.85677143236, 14332.713345733235, 14414.351354189086, 13279.999999999807, 14209.50266740659, 14235.644108897153, 14240.643983900278, 14248.0, 10279.794404111875, 10588.364698118137, 10588.364698118137, 10603.0, 10644.946775266075, 5936.465718085393, 6796.286389928986, 6862.0, 6918.446524278146, 6926.307369263033, 10694.839577406501, 12242.693932651755, 12489.375531223584, 12549.623511294676, 12580.370981451073, 12732.681682958, 13022.674433139247, 13126.14680045794, 13138.73722525525, 13141.54004609834, 8277.793055173428, 8415.326773857954, 9304.95347523258, 9521.666741664008, 9579.0, 10763.569457221623, 10883.074938630305, 10971.890281097407, 10989.285696429706, 11001.669949901516, 9092.772680683036, 9138.543072846198, 9148.771280717769, 9161.633534658806, 9224.354295199271, 8809.295256379346, 8919.42023768453, 9005.729828105168, 9021.503817290111, 9050.818983620422, 7992.760217193493, 8245.711400100967, 8260.173982601691, 8273.255407013397, 8364.996200455984, 5068.265101560327, 5935.762569497172, 6011.969940150271, 6130.693465326805, 6149.692515374303, 5956.612820166675, 5978.701064946822, 6024.548158888013, 6034.818955431344, 6230.968845155745], "value": 5265.214705026727, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.5002346925528, 398.3009817769814, 402.3622558245181, 403.3767828704651, 407.19782029402074, 4163.9583604163945, 4621.861344159673, 4693.953060469395, 4841.443234028088, 5145.588352931766, 4959.52023988006, 5015.3730783652045, 5065.366829146356, 5122.6926384416965, 5134.383873935133, 3859.36320507116, 3933.488646475961, 3959.802009899508, 4322.82708691652, 4330.501992270898, 3992.3013472642315, 3994.0813612869056, 4297.699161058727, 4341.479022517302, 4384.347469780769, 3646.1613828819386, 3725.459808327791, 4130.97934510327, 4237.626928919799, 4307.363201983258, 3648.5804132524836, 4199.496060472732, 4210.578942105765, 4210.936835947463, 4294.763787991659, 3523.6652518010746, 3649.671529562352, 3910.413437984325, 3918.6473217410285, 3957.703172262092, 3763.41667041609, 4891.670871278909, 4971.527704868032, 5039.44566097729, 5045.571126454256, 4796.688215265996, 4931.827386041471, 4964.776585053682, 4972.254161875711, 4979.30289759434, 5551.279103477935, 5580.972095139539, 5602.59935016246, 5617.325920889479, 5641.802536911229, 4251.4685664291965, 4475.865724028252, 4492.955070449287, 4517.299818528133, 4525.646663734839, 5518.365387980353, 5632.3804381518, 5634.295713035871, 5810.564207684441, 5944.286685597713, 4847.636427267969, 4915.21356582945, 4966.0, 4975.925361119586, 5046.082488654288, 4709.340692303081, 4796.8081276748535, 5108.795648174031, 5122.771594356467, 5167.68993860362, 4822.0, 4872.634552408584, 5192.662476938987, 5285.814996475182, 5293.655912365684, 4689.038747056845, 4771.880702982384, 5549.0, 5602.2717046783655, 5661.575381846378, 1486.6357742352977, 1584.9841501584835, 1680.6722689075493, 1754.1141723429841, 1763.1448747357522, 1169.4157407545845, 1319.300770591588, 1420.5241244183314, 1430.957071287863, 1436.5403071017383, 4390.792532053711, 4544.500104988449, 4965.113256962331, 5126.7180305083575, 5196.870078247998, 5406.782454931011, 5727.11229759388, 5787.768489260382, 5792.565557583198, 5826.212961574925, 4391.3193455014525, 5148.506932989391, 5162.6128040397125, 5172.301739265151, 5201.763241805984, 2465.23735529094, 2583.8320509166842, 2923.8245705257755, 2990.7457866081627, 3006.2183832203787, 4910.508949105061, 5116.948830511722, 5274.446183150791, 5662.5753068520025, 5894.381089985575, 5639.689817060019, 5668.319801623749, 5744.454276843693, 5810.941890581124, 5884.764609415575, 5673.574481913791, 6005.189299444606, 6017.877753738703, 6031.325722565047, 6074.51403887688, 4772.5227477252, 5247.0, 5428.945710543002, 5524.33707955048, 5702.775917392138, 4631.976840115778, 4781.569658730731, 5009.749512524432, 5390.919136212881, 5544.473275038865, 4840.782164802522, 5746.885062298647, 5791.739371728367, 5863.560232982458, 5868.911966320594, 4490.640748740027, 4564.292534657134, 4588.4952655207235, 4588.587027167571, 4626.676132670681, 4751.976240118777, 5718.51392631631, 5745.569082318926, 5748.281464816898, 5820.73806678692, 4540.72755634663, 4766.547178018082, 5351.518363347317, 5541.972290138685, 5604.103343465026, 4369.912601748011, 4678.766061696833, 5189.534970888798, 5239.554637855825, 5535.280413546213, 5153.896922061613, 5411.996342030211, 5427.158790387496, 5475.946520054979, 5488.639884849553, 4496.640268778555, 5325.0148722486465, 5496.395396506461, 5510.641808282609, 5522.392536820867, 4687.39063921688, 5206.140986737146, 5412.702301373462, 5413.40452550227, 5428.267183930119], "value": 4277.764342513016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.4424902568204, 811.2576992052273, 835.1481488881337, 882.9117088291172, 894.1416240409209, 15724.213789310532, 17338.306467741306, 17370.739438908426, 17389.39137130201, 17399.913000434997, 14310.35603397847, 16024.919875400607, 16216.270267837946, 16216.945898516587, 16281.674366512663, 1002.2633364477103, 1018.7758693087509, 1039.1063685230695, 1039.537405854395, 1041.182671602792, 1037.678319720889, 1040.2458217792077, 1041.8072656558522, 1041.9010194031555, 1041.927065105443, 1051.6056478820442, 1053.062774131024, 1055.208593554834, 1056.2711728907068, 1056.799208150449, 1048.1091072588315, 1050.2280823594651, 1051.5478344311944, 1052.9631462898801, 1053.9934362683618, 773.435392163718, 774.44240147094, 774.6901239504188, 775.3448336155939, 779.4037561265636, 14812.185684719869, 15142.621434464116, 15303.464378746801, 15486.457973970857, 15500.612484687974, 12284.078694097889, 12424.689382765413, 12490.830975584115, 12666.80999785004, 12858.935705321506, 11333.603323883624, 11357.659270221906, 11449.942750286278, 11906.107041971887, 12101.394930253453, 3753.418220175877, 3763.1909139535132, 3831.3103641344546, 3847.2305538892056, 3965.365541513373, 90954.5452272741, 120861.0, 121423.39288303502, 122089.38955305255, 123445.38277308556, 9713.90286097144, 9769.413835169771, 9932.602695892083, 10091.545880435398, 10230.693079207636, 21836.45408864791, 22235.110595575996, 22329.106835726714, 22338.441538961655, 22436.0, 8273.669053237923, 8328.916710832935, 8477.830443391098, 8498.532580707997, 8539.402241843012, 4493.977530112328, 4530.411046563926, 4588.518205588432, 4659.6505262105475, 4663.323818046364, 3488.7383446241633, 3589.9948014555966, 3590.4075827488696, 3593.2274560969518, 3593.2454184621524, 575.0856138739424, 602.8281939647179, 617.2346290599714, 621.9751209951642, 625.0468036244672, 661.7485355564854, 698.1691786773723, 725.2022774947516, 734.4418242136015, 735.8086897406606, 10041.799164016826, 10293.485325733684, 10492.842607360895, 10666.89333106659, 10758.784824303626, 614.7878981751288, 674.9865002699919, 733.4132693844903, 737.9963100184465, 738.7192866710616, 4888.413390393175, 4929.901401971941, 4974.179260422062, 4974.427940786856, 4997.025580011863, 84740.0, 106607.4669626647, 108574.0, 110737.0, 113609.4319528397, 55194.0, 57478.71260643753, 92970.53514732383, 94757.57863632051, 95762.0, 65478.67260663666, 85136.57431712802, 87126.56436717777, 88589.0, 89698.10301897029, 10834.29577077519, 11011.944940275247, 11320.433978300887, 11399.772004559696, 11435.714107147387, 38654.033649159, 45977.540224597324, 49437.50562494329, 49697.75151124221, 50887.98224035424, 67412.97761067176, 69258.0, 69541.30458695348, 69991.0, 70431.0, 14147.64630884195, 14546.927265363605, 14861.256937153315, 14920.925395372953, 15106.697866042838, 15474.994125381814, 15823.841761582236, 16229.59426014359, 16231.837681623032, 16922.746158807382, 13986.580402587939, 14536.636584085483, 14718.601732835363, 14949.551513454613, 15049.849501504845, 13898.930505347813, 14014.0, 14899.627509312353, 15006.02460840042, 15370.538883833502, 13330.866876315622, 14638.121712697272, 14661.48684796027, 14811.25943702789, 14983.47557835514, 10044.648437304952, 10242.897571024194, 10419.95800419952, 10478.0, 10578.89421105779, 11025.44872756375, 11331.320120792449, 11631.243969141979, 11863.58477453285, 12056.156069075081], "value": 7916.061162892801, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1422.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 111.0, 160.0, 133.0, 109.0, 109.0, 107.0, 127.0, 106.0], "value": 121.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 185.0, 183.0, 183.0, 185.0, 185.0, 183.0, 187.0, 184.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 61.0, 96.0, 88.0, 78.0, 59.0, 51.0, 76.0, 51.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 86.0, 69.0, 68.0, 69.0, 81.0, 69.0, 69.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 86.0, 87.0, 86.0, 89.0, 133.0, 87.0, 86.0, 87.0, 86.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 100.0, 143.0, 94.0, 93.0, 92.0, 95.0, 93.0, 93.0], "value": 98.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 86.0, 86.0, 83.0, 84.0, 84.0, 84.0, 82.0, 82.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 58.0, 59.0, 62.0, 61.0, 62.0, 63.0, 62.0, 62.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 60.0, 68.0, 62.0, 59.0, 59.0, 59.0, 58.0, 58.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 80.0, 84.0, 80.0, 82.0, 80.0, 85.0, 86.0, 80.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 134.0, 133.0, 145.0, 132.0, 135.0, 138.0, 176.0, 151.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 173.0, 205.0, 188.0, 164.0, 162.0, 165.0, 163.0, 164.0, 164.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0], "value": 64.1, "unit": "score"}]}]} 03:40:03 INFO - Return code: 0 03:40:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:40:03 INFO - # TBPL SUCCESS # 03:40:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:40:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:40:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:40:03 INFO - 2017-06-02 03:40:03.492 screenresolution[1950:11024] starting screenresolution argv=screenresolution get 03:40:03 INFO - 2017-06-02 03:40:03.495 screenresolution[1950:11024] Display 0: 1600x1200x32@60 03:40:03 INFO - 2017-06-02 03:40:03.501 screenresolution[1951:11027] starting screenresolution argv=screenresolution list 03:40:03 INFO - Available Modes on Display 0 03:40:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:40:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:40:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:40:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:40:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:40:03 INFO - Intel Iris: 03:40:03 INFO - Chipset Model: Intel Iris 03:40:03 INFO - Type: GPU 03:40:03 INFO - Bus: Built-In 03:40:03 INFO - VRAM (Dynamic, Max): 1536 MB 03:40:03 INFO - Vendor: Intel (0x8086) 03:40:03 INFO - Device ID: 0x0a2e 03:40:03 INFO - Revision ID: 0x0009 03:40:03 INFO - Displays: 03:40:03 INFO - Display: 03:40:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:40:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:40:03 INFO - Display Serial Number: 1600x1200 60 03:40:03 INFO - Main Display: Yes 03:40:03 INFO - Mirror: Off 03:40:03 INFO - Online: Yes 03:40:03 INFO - Rotation: Supported 03:40:03 INFO - Return code: 0 03:40:03 INFO - Running post-action listener: _resource_record_post_action 03:40:03 INFO - [mozharness: 2017-06-02 10:40:03.734050Z] Finished run-tests step (success) 03:40:03 INFO - Running post-run listener: _resource_record_post_run 03:40:03 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 2326528; Write bytes: 421047296; Read time: 23; Write time: 1382 03:40:03 INFO - TinderboxPrint: CPU usage
53.9% 03:40:03 INFO - TinderboxPrint: I/O read bytes / time
2,326,528 / 23 03:40:03 INFO - TinderboxPrint: I/O write bytes / time
421,047,296 / 1,382 03:40:03 INFO - TinderboxPrint: CPU idle
2,203.1 (46.1%) 03:40:03 INFO - TinderboxPrint: CPU system
953.4 (20.0%) 03:40:03 INFO - TinderboxPrint: CPU user
1,621.2 (33.9%) 03:40:03 INFO - TinderboxPrint: Swap in / out
124,956,672 / 0 03:40:03 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121653760; Write bytes: 114233344; Read time: 11044; Write time: 247 03:40:03 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1425408; Write bytes: 296754176; Read time: 20; Write time: 1097 03:40:04 INFO - Running post-run listener: _upload_blobber_files 03:40:04 INFO - Blob upload gear active. 03:40:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:40:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:40:04 INFO - (blobuploader) - INFO - Open directory for files ... 03:40:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:40:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:40:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:40:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:40:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:40:05 INFO - (blobuploader) - INFO - Done attempting. 03:40:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:40:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:40:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:40:06 INFO - (blobuploader) - INFO - Done attempting. 03:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:40:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:40:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:40:07 INFO - (blobuploader) - INFO - Done attempting. 03:40:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:40:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:40:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:40:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:40:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:40:09 INFO - (blobuploader) - INFO - Done attempting. 03:40:09 INFO - (blobuploader) - INFO - Iteration through files over. 03:40:09 INFO - Return code: 0 03:40:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:40:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:40:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8cdfb17a75e5fd25e3daf4fb9ff06abdd8147be8f981af8a07606faa4e8d07ede73dc2ad03f34e35ec085d2b3da1d371980c92a0afdc6626d3b5f565a8dcc9b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/253a20949b97f3ba11371618103e9f55b4a6b45c73360278bf92573b1e51b9acf4de7c1533f82ad00774e72c570219462a7dc9f1e192fa4af548ec62b7e0ef60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3df99610394aecdc47ceb2ed33b7b1e481ef95e8ded8277785254bf0b3e3176d5e90f976c177e3a3fa5c75d846ea0f4abc8f4cbb235a75d660f666c9f98c4562", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769cea5cacc47da670ae69cbc53d63e1dbc3cf3911d9d6e7aa6ec5db07575a95815cb8bca8b2a86465de6097eb788c93c077a09ea4b928107980fa25677a9a6c"} 03:40:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:40:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:40:09 INFO - Contents: 03:40:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8cdfb17a75e5fd25e3daf4fb9ff06abdd8147be8f981af8a07606faa4e8d07ede73dc2ad03f34e35ec085d2b3da1d371980c92a0afdc6626d3b5f565a8dcc9b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/253a20949b97f3ba11371618103e9f55b4a6b45c73360278bf92573b1e51b9acf4de7c1533f82ad00774e72c570219462a7dc9f1e192fa4af548ec62b7e0ef60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3df99610394aecdc47ceb2ed33b7b1e481ef95e8ded8277785254bf0b3e3176d5e90f976c177e3a3fa5c75d846ea0f4abc8f4cbb235a75d660f666c9f98c4562", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769cea5cacc47da670ae69cbc53d63e1dbc3cf3911d9d6e7aa6ec5db07575a95815cb8bca8b2a86465de6097eb788c93c077a09ea4b928107980fa25677a9a6c"} 03:40:09 INFO - Running post-run listener: copy_logs_to_upload_dir 03:40:09 INFO - Copying logs to upload dir... 03:40:09 INFO - mkdir: /builds/slave/test/build/upload/logs 03:40:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.345456 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-06-02 03:40:09.981404) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 03:40:09.985219) ========= 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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8cdfb17a75e5fd25e3daf4fb9ff06abdd8147be8f981af8a07606faa4e8d07ede73dc2ad03f34e35ec085d2b3da1d371980c92a0afdc6626d3b5f565a8dcc9b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/253a20949b97f3ba11371618103e9f55b4a6b45c73360278bf92573b1e51b9acf4de7c1533f82ad00774e72c570219462a7dc9f1e192fa4af548ec62b7e0ef60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3df99610394aecdc47ceb2ed33b7b1e481ef95e8ded8277785254bf0b3e3176d5e90f976c177e3a3fa5c75d846ea0f4abc8f4cbb235a75d660f666c9f98c4562", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769cea5cacc47da670ae69cbc53d63e1dbc3cf3911d9d6e7aa6ec5db07575a95815cb8bca8b2a86465de6097eb788c93c077a09ea4b928107980fa25677a9a6c"} build_url:https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011240 build_url: 'https://queue.taskcluster.net/v1/task/GHztW0gmQhanpkfTJ1QAVA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8cdfb17a75e5fd25e3daf4fb9ff06abdd8147be8f981af8a07606faa4e8d07ede73dc2ad03f34e35ec085d2b3da1d371980c92a0afdc6626d3b5f565a8dcc9b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/253a20949b97f3ba11371618103e9f55b4a6b45c73360278bf92573b1e51b9acf4de7c1533f82ad00774e72c570219462a7dc9f1e192fa4af548ec62b7e0ef60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3df99610394aecdc47ceb2ed33b7b1e481ef95e8ded8277785254bf0b3e3176d5e90f976c177e3a3fa5c75d846ea0f4abc8f4cbb235a75d660f666c9f98c4562", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/769cea5cacc47da670ae69cbc53d63e1dbc3cf3911d9d6e7aa6ec5db07575a95815cb8bca8b2a86465de6097eb788c93c077a09ea4b928107980fa25677a9a6c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 03:40:10.018454) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 03:40:10.018760) ========= 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.GGmO3F9OTr/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.IwcsnCcnn2/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.005397 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 03:40:10.067407) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 03:40:10.067715) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 03:40:10.068027) ========= ========= Total master_lag: 0.18 =========