builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0293 starttime: 1497347303.79 results: success (0) revision: 9f97bc5242590a32204233597ca24ad39ff42847 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:23.785330) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:23.785749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:23.786035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:23.812421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:23.812861) ========= 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.YU3xgden3H/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.dKdnEtbaNu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-13 02:48:23-- 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% 232M=0s 2017-06-13 02:48:23 (232 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125355 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:23.962644) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:23.962983) ========= 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.YU3xgden3H/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.dKdnEtbaNu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.032035 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:24.020315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:24.020655) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9f97bc5242590a32204233597ca24ad39ff42847 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9f97bc5242590a32204233597ca24ad39ff42847 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YU3xgden3H/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.dKdnEtbaNu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-13 02:48:24,119 truncating revision to first 12 chars 2017-06-13 02:48:24,119 Setting DEBUG logging. 2017-06-13 02:48:24,119 attempt 1/10 2017-06-13 02:48:24,119 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9f97bc524259?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 02:48:24,826 unpacking tar archive at: mozilla-beta-9f97bc524259/testing/mozharness/ program finished with exit code 0 elapsedTime=0.974080 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:25.019049) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:25.019382) ========= script_repo_revision: 9f97bc5242590a32204233597ca24ad39ff42847 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:25.019760) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:25.020024) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 02:48:25.038367) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-13 02:48:25.038640) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.YU3xgden3H/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.dKdnEtbaNu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:48:25 INFO - MultiFileLogger online at 20170613 02:48:25 in /builds/slave/test 02:48:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 02:48:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:48:25 INFO - {'append_to_log': False, 02:48:25 INFO - 'base_work_dir': '/builds/slave/test', 02:48:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:48:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:48:25 INFO - 'branch': 'Mozilla-Beta', 02:48:25 INFO - 'buildbot_json_path': 'buildprops.json', 02:48:25 INFO - 'config_files': ('talos/mac_config.py',), 02:48:25 INFO - 'default_actions': ('clobber', 02:48:25 INFO - 'read-buildbot-config', 02:48:25 INFO - 'download-and-extract', 02:48:25 INFO - 'populate-webroot', 02:48:25 INFO - 'create-virtualenv', 02:48:25 INFO - 'install', 02:48:25 INFO - 'setup-mitmproxy', 02:48:25 INFO - 'run-tests'), 02:48:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:48:25 INFO - 'download_minidump_stackwalk': True, 02:48:25 INFO - 'download_symbols': 'ondemand', 02:48:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:48:25 INFO - 'tooltool.py': '/tools/tooltool.py', 02:48:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:48:25 INFO - '/tools/misc-python/virtualenv.py')}, 02:48:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:48:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:48:25 INFO - 'gecko_profile': False, 02:48:25 INFO - 'gecko_profile_interval': 0, 02:48:25 INFO - 'installer_path': 'installer.exe', 02:48:25 INFO - 'log_level': 'info', 02:48:25 INFO - 'log_name': 'talos', 02:48:25 INFO - 'log_to_console': True, 02:48:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:48:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:48:25 INFO - 'opt_config_files': (), 02:48:25 INFO - 'pip_index': False, 02:48:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:48:25 INFO - 'cmd': ('bash', 02:48:25 INFO - '-c', 02:48:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:48:25 INFO - 'enabled': True, 02:48:25 INFO - 'halt_on_failure': False, 02:48:25 INFO - 'name': 'check_screen_resolution'},), 02:48:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:48:25 INFO - 'cmd': ('bash', 02:48:25 INFO - '-c', 02:48:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:48:25 INFO - 'enabled': True, 02:48:25 INFO - 'halt_on_failure': False, 02:48:25 INFO - 'name': 'check_screen_resolution'},), 02:48:25 INFO - 'run_cmd_checks_enabled': True, 02:48:25 INFO - 'suite': 'dromaeojs-e10s', 02:48:25 INFO - 'system_bits': '32', 02:48:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:48:25 INFO - 'title': 't-yosemite-r7-0293', 02:48:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:48:25 INFO - 'use_talos_json': True, 02:48:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:48:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:48:25 INFO - 'work_dir': 'build'} 02:48:25 INFO - [mozharness: 2017-06-13 09:48:25.245382Z] Running clobber step. 02:48:25 INFO - Running pre-action listener: _resource_record_pre_action 02:48:25 INFO - Running main action method: clobber 02:48:25 INFO - rmtree: /builds/slave/test/build 02:48:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:48:27 INFO - Running post-action listener: _resource_record_post_action 02:48:27 INFO - [mozharness: 2017-06-13 09:48:27.725632Z] Finished clobber step (success) 02:48:27 INFO - [mozharness: 2017-06-13 09:48:27.725761Z] Running read-buildbot-config step. 02:48:27 INFO - Running pre-action listener: _resource_record_pre_action 02:48:27 INFO - Running main action method: read_buildbot_config 02:48:27 INFO - Using buildbot properties: 02:48:27 INFO - { 02:48:27 INFO - "project": "", 02:48:27 INFO - "product": "firefox", 02:48:27 INFO - "who": "ffxbld@noreply.mozilla.org", 02:48:27 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 02:48:27 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 02:48:27 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 02:48:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 02:48:27 INFO - "stage_platform": "macosx64", 02:48:27 INFO - "basedir": "/builds/slave/test", 02:48:27 INFO - "buildnumber": 54, 02:48:27 INFO - "platform": "yosemite_r7", 02:48:27 INFO - "parent_task_id": "Adz_gDxNSDOtawVjWf_fZA", 02:48:27 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:48:27 INFO - "slavebuilddir": "test", 02:48:27 INFO - "taskId": "EjexKK5YQxOnqf7eFvmgmg", 02:48:27 INFO - "branch": "mozilla-beta", 02:48:27 INFO - "script_repo_revision": "production", 02:48:27 INFO - "revision": "9f97bc5242590a32204233597ca24ad39ff42847", 02:48:27 INFO - "slavename": "t-yosemite-r7-0293", 02:48:27 INFO - "repo_path": "releases/mozilla-beta" 02:48:27 INFO - } 02:48:27 INFO - Finding installer, test and symbols from parent task. 02:48:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EjexKK5YQxOnqf7eFvmgmg'}, attempt #1 02:48:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EjexKK5YQxOnqf7eFvmgmg'}, attempt #1 02:48:28 INFO - Task dependencies: IF9wiSg1R3iuLIh085hUMA Adz_gDxNSDOtawVjWf_fZA 02:48:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA'}, attempt #1 02:48:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 02:48:29 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/test_packages.json 02:48:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/target.crashreporter-symbols.zip 02:48:29 INFO - Running post-action listener: _resource_record_post_action 02:48:29 INFO - [mozharness: 2017-06-13 09:48:29.084823Z] Finished read-buildbot-config step (success) 02:48:29 INFO - [mozharness: 2017-06-13 09:48:29.084963Z] Running download-and-extract step. 02:48:29 INFO - Running pre-action listener: _resource_record_pre_action 02:48:29 INFO - Running main action method: download_and_extract 02:48:29 INFO - mkdir: /builds/slave/test/build/tests 02:48:29 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')]} 02:48:29 INFO - trying https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 02:48:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 02:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 02:48:30 INFO - Downloaded 1290 bytes. 02:48:30 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 02:48:30 INFO - Using the following test package requirements: 02:48:30 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 02:48:30 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 02:48:30 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 02:48:30 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 02:48:30 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:48:30 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 02:48:30 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 02:48:30 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 02:48:30 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 02:48:30 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 02:48:30 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 02:48:30 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 02:48:30 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 02:48:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 02:48:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 02:48:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 02:48:31 INFO - Content-Length response header: 58983203 02:48:31 INFO - Bytes received: 58983203 02:48:49 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 02:48:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 02:48:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 02:48:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 02:48:51 INFO - Content-Length response header: 58983203 02:48:51 INFO - Bytes received: 58983203 02:49:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 02:49:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 02:49:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 02:49:09 INFO - Content-Length response header: 14450018 02:49:09 INFO - Bytes received: 14450018 02:49:10 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')]} 02:49:10 INFO - trying https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 02:49:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 02:49:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 02:49:11 INFO - Downloaded 51079090 bytes. 02:49:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 02:49:11 INFO - mkdir: /builds/slave/test/properties 02:49:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:49:11 INFO - Writing to file /builds/slave/test/properties/build_url 02:49:11 INFO - Contents: 02:49:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 02:49:11 INFO - Running post-action listener: _resource_record_post_action 02:49:11 INFO - Running post-action listener: set_extra_try_arguments 02:49:11 INFO - [mozharness: 2017-06-13 09:49:11.660957Z] Finished download-and-extract step (success) 02:49:11 INFO - [mozharness: 2017-06-13 09:49:11.661095Z] Running populate-webroot step. 02:49:11 INFO - Running pre-action listener: _resource_record_pre_action 02:49:11 INFO - Running main action method: populate_webroot 02:49:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:49:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:49:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:49:11 INFO - '--tppagecycles', 02:49:11 INFO - '7'], 02:49:11 INFO - 'tests': ['tsvgm']}}, 02:49:11 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:49:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:49:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:49:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['damp', 'tps']}, 02:49:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'tests': ['damp', 'tps']}, 02:49:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['dromaeo_dom']}, 02:49:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:49:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:49:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:49:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:49:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['a11yr', 02:49:11 INFO - 'ts_paint', 02:49:11 INFO - 'tpaint', 02:49:11 INFO - 'sessionrestore', 02:49:11 INFO - 'sessionrestore_no_auto_restore', 02:49:11 INFO - 'tabpaint']}, 02:49:11 INFO - 'other-e10s': {'tests': ['a11yr', 02:49:11 INFO - 'ts_paint', 02:49:11 INFO - 'tpaint', 02:49:11 INFO - 'sessionrestore', 02:49:11 INFO - 'sessionrestore_no_auto_restore', 02:49:11 INFO - 'tabpaint']}, 02:49:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:49:11 INFO - 'ts_paint', 02:49:11 INFO - 'tpaint', 02:49:11 INFO - 'sessionrestore', 02:49:11 INFO - 'sessionrestore_no_auto_restore', 02:49:11 INFO - 'tabpaint']}, 02:49:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:49:11 INFO - 'ts_paint', 02:49:11 INFO - 'tpaint', 02:49:11 INFO - 'sessionrestore', 02:49:11 INFO - 'sessionrestore_no_auto_restore', 02:49:11 INFO - 'tabpaint']}, 02:49:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['a11yr', 02:49:11 INFO - 'ts_paint', 02:49:11 INFO - 'tpaint', 02:49:11 INFO - 'sessionrestore', 02:49:11 INFO - 'sessionrestore_no_auto_restore', 02:49:11 INFO - 'tabpaint']}, 02:49:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['a11yr', 02:49:11 INFO - 'ts_paint', 02:49:11 INFO - 'tpaint', 02:49:11 INFO - 'sessionrestore', 02:49:11 INFO - 'sessionrestore_no_auto_restore', 02:49:11 INFO - 'tabpaint']}, 02:49:11 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:49:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:49:11 INFO - 'bloom_basic_ref']}, 02:49:11 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:49:11 INFO - 'talos_options': ['--mitmproxy', 02:49:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:49:11 INFO - '--firstNonBlankPaint'], 02:49:11 INFO - 'tests': ['quantum_pageload_google', 02:49:11 INFO - 'quantum_pageload_youtube', 02:49:11 INFO - 'quantum_pageload_amazon', 02:49:11 INFO - 'quantum_pageload_facebook']}, 02:49:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['tsvgx', 02:49:11 INFO - 'tsvgr_opacity', 02:49:11 INFO - 'tart', 02:49:11 INFO - 'tscrollx', 02:49:11 INFO - 'cart', 02:49:11 INFO - 'tsvg_static']}, 02:49:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:49:11 INFO - 'tsvgr_opacity', 02:49:11 INFO - 'tart', 02:49:11 INFO - 'tscrollx', 02:49:11 INFO - 'cart', 02:49:11 INFO - 'tsvg_static']}, 02:49:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'talos_options': ['--disable-e10s'], 02:49:11 INFO - 'tests': ['tp5o']}, 02:49:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:49:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'talos_options': ['--disable-e10s', 02:49:11 INFO - '--xperf_path', 02:49:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:11 INFO - 'tests': ['tp5n']}, 02:49:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:49:11 INFO - 'talos_options': ['--xperf_path', 02:49:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:11 INFO - 'tests': ['tp5n']}}, 02:49:11 INFO - 'talos.zip': {'path': '', 02:49:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:49:11 INFO - Running post-action listener: _resource_record_post_action 02:49:11 INFO - [mozharness: 2017-06-13 09:49:11.684706Z] Finished populate-webroot step (success) 02:49:11 INFO - [mozharness: 2017-06-13 09:49:11.684847Z] Running create-virtualenv step. 02:49:11 INFO - Running pre-action listener: _resource_record_pre_action 02:49:11 INFO - Running main action method: create_virtualenv 02:49:11 INFO - Creating virtualenv /builds/slave/test/build/venv 02:49:11 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 02:49:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:49:11 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:49:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:49:11 INFO - Using real prefix '/tools/python27' 02:49:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:49:12 INFO - Installing distribute.............................................................................................................................................................................................done. 02:49:15 INFO - Installing pip.................done. 02:49:15 INFO - Return code: 0 02:49:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:49:15 INFO - Reading from file tmpfile_stdout 02:49:15 INFO - Output received: 02:49:15 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) 02:49:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:49:15 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')]} 02:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:15 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')]} 02:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:15 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 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:15 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 02:49:15 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 02:49:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:15 INFO - 'HOME': '/Users/cltbld', 02:49:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:15 INFO - 'LOGNAME': 'cltbld', 02:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:15 INFO - 'MOZ_NO_REMOTE': '1', 02:49:15 INFO - 'NO_EM_RESTART': '1', 02:49:15 INFO - 'PAGER': '/bin/cat', 02:49:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:15 INFO - 'PWD': '/builds/slave/test', 02:49:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:15 INFO - 'SHELL': '/bin/bash', 02:49:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:15 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:15 INFO - 'USER': 'cltbld', 02:49:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:15 INFO - 'XPC_FLAGS': '0x0', 02:49:15 INFO - 'XPC_SERVICE_NAME': '0', 02:49:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:15 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 02:49:15 INFO - Cleaning up... 02:49:15 INFO - Return code: 0 02:49:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:49:15 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')]} 02:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:16 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')]} 02:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:16 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 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:16 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 02:49:16 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 02:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:16 INFO - 'HOME': '/Users/cltbld', 02:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:16 INFO - 'LOGNAME': 'cltbld', 02:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:16 INFO - 'MOZ_NO_REMOTE': '1', 02:49:16 INFO - 'NO_EM_RESTART': '1', 02:49:16 INFO - 'PAGER': '/bin/cat', 02:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:16 INFO - 'PWD': '/builds/slave/test', 02:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:16 INFO - 'SHELL': '/bin/bash', 02:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:16 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:16 INFO - 'USER': 'cltbld', 02:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:16 INFO - 'XPC_FLAGS': '0x0', 02:49:16 INFO - 'XPC_SERVICE_NAME': '0', 02:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:16 INFO - Downloading/unpacking psutil>=3.1.1 02:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:49:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:18 INFO - Installing collected packages: psutil 02:49:18 INFO - Running setup.py install for psutil 02:49:18 INFO - building 'psutil._psutil_osx' extension 02:49:18 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 02:49:18 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 02:49:18 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 02:49:18 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 02:49:19 INFO - building 'psutil._psutil_posix' extension 02:49:19 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 02:49:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:49:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:49:19 INFO - ^ 02:49:19 INFO - 1 warning generated. 02:49:19 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 02:49:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:19 INFO - Successfully installed psutil 02:49:19 INFO - Cleaning up... 02:49:19 INFO - Return code: 0 02:49:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:49:19 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')]} 02:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:19 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')]} 02:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:20 INFO - 'HOME': '/Users/cltbld', 02:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:20 INFO - 'LOGNAME': 'cltbld', 02:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:20 INFO - 'MOZ_NO_REMOTE': '1', 02:49:20 INFO - 'NO_EM_RESTART': '1', 02:49:20 INFO - 'PAGER': '/bin/cat', 02:49:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:20 INFO - 'PWD': '/builds/slave/test', 02:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:20 INFO - 'SHELL': '/bin/bash', 02:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:20 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:20 INFO - 'USER': 'cltbld', 02:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:20 INFO - 'XPC_FLAGS': '0x0', 02:49:20 INFO - 'XPC_SERVICE_NAME': '0', 02:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:49:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:49:22 INFO - Installing collected packages: mozsystemmonitor 02:49:22 INFO - Running setup.py install for mozsystemmonitor 02:49:22 INFO - Successfully installed mozsystemmonitor 02:49:22 INFO - Cleaning up... 02:49:22 INFO - Return code: 0 02:49:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:49:22 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')]} 02:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:22 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')]} 02:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:22 INFO - 'HOME': '/Users/cltbld', 02:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:22 INFO - 'LOGNAME': 'cltbld', 02:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:22 INFO - 'MOZ_NO_REMOTE': '1', 02:49:22 INFO - 'NO_EM_RESTART': '1', 02:49:22 INFO - 'PAGER': '/bin/cat', 02:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:22 INFO - 'PWD': '/builds/slave/test', 02:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:22 INFO - 'SHELL': '/bin/bash', 02:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:22 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:22 INFO - 'USER': 'cltbld', 02:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:22 INFO - 'XPC_FLAGS': '0x0', 02:49:22 INFO - 'XPC_SERVICE_NAME': '0', 02:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:22 INFO - Downloading/unpacking jsonschema==2.5.1 02:49:22 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 02:49:22 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 02:49:22 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 02:49:22 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 02:49:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:49:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:49:23 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 02:49:23 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 02:49:23 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 02:49:23 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 02:49:24 INFO - Downloading functools32-3.2.3-2.tar.gz 02:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:49:24 INFO - warning: no files found matching '*.txt' 02:49:24 INFO - no previously-included directories found matching 'build' 02:49:24 INFO - no previously-included directories found matching 'dist' 02:49:24 INFO - no previously-included directories found matching '.git*' 02:49:24 INFO - Installing collected packages: jsonschema, functools32 02:49:24 INFO - Running setup.py install for functools32 02:49:24 INFO - warning: no files found matching '*.txt' 02:49:24 INFO - no previously-included directories found matching 'build' 02:49:24 INFO - no previously-included directories found matching 'dist' 02:49:24 INFO - no previously-included directories found matching '.git*' 02:49:24 INFO - Successfully installed jsonschema functools32 02:49:24 INFO - Cleaning up... 02:49:24 INFO - Return code: 0 02:49:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:49: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')]} 02:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49: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')]} 02:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:24 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 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:24 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 02:49:24 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 02:49:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:24 INFO - 'HOME': '/Users/cltbld', 02:49:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:24 INFO - 'LOGNAME': 'cltbld', 02:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:24 INFO - 'MOZ_NO_REMOTE': '1', 02:49:24 INFO - 'NO_EM_RESTART': '1', 02:49:24 INFO - 'PAGER': '/bin/cat', 02:49:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:24 INFO - 'PWD': '/builds/slave/test', 02:49:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:24 INFO - 'SHELL': '/bin/bash', 02:49:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:24 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:24 INFO - 'USER': 'cltbld', 02:49:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:24 INFO - 'XPC_FLAGS': '0x0', 02:49:24 INFO - 'XPC_SERVICE_NAME': '0', 02:49:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:49:24 INFO - Cleaning up... 02:49:24 INFO - Return code: 0 02:49:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:49: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')]} 02:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49: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')]} 02:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:24 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 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:24 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 02:49:24 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 02:49:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:24 INFO - 'HOME': '/Users/cltbld', 02:49:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:24 INFO - 'LOGNAME': 'cltbld', 02:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:24 INFO - 'MOZ_NO_REMOTE': '1', 02:49:24 INFO - 'NO_EM_RESTART': '1', 02:49:24 INFO - 'PAGER': '/bin/cat', 02:49:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:24 INFO - 'PWD': '/builds/slave/test', 02:49:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:24 INFO - 'SHELL': '/bin/bash', 02:49:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:24 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:24 INFO - 'USER': 'cltbld', 02:49:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:24 INFO - 'XPC_FLAGS': '0x0', 02:49:24 INFO - 'XPC_SERVICE_NAME': '0', 02:49:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:25 INFO - Downloading/unpacking blobuploader==1.2.4 02:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:26 INFO - Downloading blobuploader-1.2.4.tar.gz 02:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:49:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:49:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:27 INFO - Downloading docopt-0.6.1.tar.gz 02:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:49:27 INFO - Installing collected packages: blobuploader, requests, docopt 02:49:27 INFO - Running setup.py install for blobuploader 02:49:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:49:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:49:27 INFO - Running setup.py install for requests 02:49:28 INFO - Running setup.py install for docopt 02:49:28 INFO - Successfully installed blobuploader requests docopt 02:49:28 INFO - Cleaning up... 02:49:28 INFO - Return code: 0 02:49:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:28 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')]} 02:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:28 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')]} 02:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:28 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 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:28 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 02:49:28 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 02:49:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:28 INFO - 'HOME': '/Users/cltbld', 02:49:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:28 INFO - 'LOGNAME': 'cltbld', 02:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:28 INFO - 'MOZ_NO_REMOTE': '1', 02:49:28 INFO - 'NO_EM_RESTART': '1', 02:49:28 INFO - 'PAGER': '/bin/cat', 02:49:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:28 INFO - 'PWD': '/builds/slave/test', 02:49:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:28 INFO - 'SHELL': '/bin/bash', 02:49:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:28 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:28 INFO - 'USER': 'cltbld', 02:49:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:28 INFO - 'XPC_FLAGS': '0x0', 02:49:28 INFO - 'XPC_SERVICE_NAME': '0', 02:49:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:28 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-xak2dJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:28 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-i9x3pS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:28 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ITF2Yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:28 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-mRd_Sw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:28 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Uns9wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-v7jhj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Tczxbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-d6LgDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-pQXUc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ayL7pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-31pw5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-vjpgeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Its4M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-lk2Iff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:29 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-9jRvpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:30 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-zNLOFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:30 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-vKuSKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:49:30 INFO - Running setup.py install for manifestparser 02:49:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:49:30 INFO - Running setup.py install for mozcrash 02:49:30 INFO - Running setup.py install for mozdebug 02:49:30 INFO - Running setup.py install for mozdevice 02:49:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:49:30 INFO - Running setup.py install for mozfile 02:49:30 INFO - Running setup.py install for mozhttpd 02:49:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for mozinfo 02:49:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for mozInstall 02:49:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for mozleak 02:49:31 INFO - Running setup.py install for mozlog 02:49:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for moznetwork 02:49:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for mozprocess 02:49:32 INFO - Running setup.py install for mozprofile 02:49:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:49:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:49:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:49:32 INFO - Running setup.py install for mozrunner 02:49:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:49:32 INFO - Running setup.py install for mozscreenshot 02:49:32 INFO - Running setup.py install for moztest 02:49:32 INFO - Running setup.py install for mozversion 02:49:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:49:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:49:32 INFO - Cleaning up... 02:49:32 INFO - Return code: 0 02:49:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:32 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')]} 02:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:32 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')]} 02:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:32 INFO - 'HOME': '/Users/cltbld', 02:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:32 INFO - 'LOGNAME': 'cltbld', 02:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:32 INFO - 'MOZ_NO_REMOTE': '1', 02:49:32 INFO - 'NO_EM_RESTART': '1', 02:49:32 INFO - 'PAGER': '/bin/cat', 02:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:32 INFO - 'PWD': '/builds/slave/test', 02:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:32 INFO - 'SHELL': '/bin/bash', 02:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:32 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:32 INFO - 'USER': 'cltbld', 02:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:32 INFO - 'XPC_FLAGS': '0x0', 02:49:32 INFO - 'XPC_SERVICE_NAME': '0', 02:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-e2COcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:33 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)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-delRpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:33 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)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-2zGqtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:33 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)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-dVLgd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-9PCgnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-i7Rhej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-kqMhHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-d520Tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:33 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-8heiGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:34 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)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-LkufhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:34 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)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-P_j7TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:34 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)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-NTm7Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:34 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)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-5wr1t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-TvY5Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Xpf6Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ZqK5Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:34 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-OmHqaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:49:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:36 INFO - Downloading blessings-1.6.tar.gz 02:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:49:36 INFO - Installing collected packages: blessings 02:49:36 INFO - Running setup.py install for blessings 02:49:36 INFO - Successfully installed blessings 02:49:36 INFO - Cleaning up... 02:49:36 INFO - Return code: 0 02:49:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:49:36 INFO - Reading from file tmpfile_stdout 02:49:36 INFO - Current package versions: 02:49:36 INFO - blessings == 1.6 02:49:36 INFO - blobuploader == 1.2.4 02:49:36 INFO - docopt == 0.6.1 02:49:36 INFO - functools32 == 3.2.3-2 02:49:36 INFO - jsonschema == 2.5.1 02:49:36 INFO - manifestparser == 1.1 02:49:36 INFO - mozInstall == 1.13 02:49:36 INFO - mozcrash == 1.0 02:49:36 INFO - mozdebug == 0.1 02:49:36 INFO - mozdevice == 0.51 02:49:36 INFO - mozfile == 1.2 02:49:36 INFO - mozhttpd == 0.7 02:49:36 INFO - mozinfo == 0.10 02:49:36 INFO - mozleak == 0.1 02:49:36 INFO - mozlog == 3.4 02:49:36 INFO - moznetwork == 0.27 02:49:36 INFO - mozprocess == 0.25 02:49:36 INFO - mozprofile == 0.28 02:49:36 INFO - mozrunner == 6.13 02:49:36 INFO - mozscreenshot == 0.1 02:49:36 INFO - mozsystemmonitor == 0.3 02:49:36 INFO - moztest == 0.8 02:49:36 INFO - mozversion == 1.4 02:49:36 INFO - psutil == 3.1.1 02:49:36 INFO - requests == 1.2.3 02:49:36 INFO - wsgiref == 0.1.2 02:49:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:36 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')]} 02:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:36 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')]} 02:49:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:49:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:36 INFO - 'HOME': '/Users/cltbld', 02:49:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:36 INFO - 'LOGNAME': 'cltbld', 02:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:36 INFO - 'MOZ_NO_REMOTE': '1', 02:49:36 INFO - 'NO_EM_RESTART': '1', 02:49:36 INFO - 'PAGER': '/bin/cat', 02:49:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:36 INFO - 'PWD': '/builds/slave/test', 02:49:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:36 INFO - 'SHELL': '/bin/bash', 02:49:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:36 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:36 INFO - 'USER': 'cltbld', 02:49:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:36 INFO - 'XPC_FLAGS': '0x0', 02:49:36 INFO - 'XPC_SERVICE_NAME': '0', 02:49:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:37 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)) 02:49:37 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)) 02:49:37 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)) 02:49:37 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)) 02:49:37 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)) 02:49:37 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)) 02:49:37 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)) 02:49:37 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)) 02:49:37 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)) 02:49:37 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:49:37 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 02:49:37 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 02:49:37 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 02:49:37 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 02:49:38 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:49:38 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)) 02:49:38 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)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:49:38 INFO - Installing collected packages: marionette-driver 02:49:38 INFO - Running setup.py install for marionette-driver 02:49:38 INFO - Successfully installed marionette-driver 02:49:38 INFO - Cleaning up... 02:49:38 INFO - Return code: 0 02:49:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:49:38 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')]} 02:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:38 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')]} 02:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:38 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 0x10b7bdc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf42488df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, '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.YU3xgden3H/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.dKdnEtbaNu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:49:38 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 02:49:38 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 02:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:38 INFO - 'HOME': '/Users/cltbld', 02:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:38 INFO - 'LOGNAME': 'cltbld', 02:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:38 INFO - 'MOZ_NO_REMOTE': '1', 02:49:38 INFO - 'NO_EM_RESTART': '1', 02:49:38 INFO - 'PAGER': '/bin/cat', 02:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:38 INFO - 'PWD': '/builds/slave/test', 02:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:38 INFO - 'SHELL': '/bin/bash', 02:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:38 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:38 INFO - 'USER': 'cltbld', 02:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:38 INFO - 'XPC_FLAGS': '0x0', 02:49:38 INFO - 'XPC_SERVICE_NAME': '0', 02:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:49:39 INFO - Cleaning up... 02:49:39 INFO - Return code: 0 02:49:39 INFO - Running post-action listener: _resource_record_post_action 02:49:39 INFO - Running post-action listener: _start_resource_monitoring 02:49:39 INFO - Starting resource monitoring. 02:49:39 INFO - [mozharness: 2017-06-13 09:49:39.179005Z] Finished create-virtualenv step (success) 02:49:39 INFO - [mozharness: 2017-06-13 09:49:39.179955Z] Running install step. 02:49:39 INFO - Running pre-action listener: _resource_record_pre_action 02:49:39 INFO - Running main action method: install 02:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:49:39 INFO - Reading from file tmpfile_stdout 02:49:39 INFO - Detecting whether we're running mozinstall >=1.0... 02:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:49:39 INFO - Reading from file tmpfile_stdout 02:49:39 INFO - Output received: 02:49:39 INFO - Usage: mozinstall [options] installer 02:49:39 INFO - Options: 02:49:39 INFO - -h, --help show this help message and exit 02:49:39 INFO - -d DEST, --destination=DEST 02:49:39 INFO - Directory to install application into. [default: 02:49:39 INFO - "/builds/slave/test"] 02:49:39 INFO - --app=APP Application being installed. [default: firefox] 02:49:39 INFO - mkdir: /builds/slave/test/build/application 02:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 02:49:55 INFO - Reading from file tmpfile_stdout 02:49:55 INFO - Output received: 02:49:55 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 02:49:55 INFO - Running post-action listener: _resource_record_post_action 02:49:55 INFO - [mozharness: 2017-06-13 09:49:55.447205Z] Finished install step (success) 02:49:55 INFO - [mozharness: 2017-06-13 09:49:55.447347Z] Running setup-mitmproxy step. 02:49:55 INFO - Running pre-action listener: _resource_record_pre_action 02:49:55 INFO - Running main action method: setup_mitmproxy 02:49:55 INFO - Skipping: mitmproxy is not required 02:49:55 INFO - Running post-action listener: _resource_record_post_action 02:49:55 INFO - [mozharness: 2017-06-13 09:49:55.447953Z] Finished setup-mitmproxy step (success) 02:49:55 INFO - [mozharness: 2017-06-13 09:49:55.448097Z] Running run-tests step. 02:49:55 INFO - Running pre-action listener: _resource_record_pre_action 02:49:55 INFO - Running main action method: run_tests 02:49:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:55 INFO - 2017-06-13 02:49:55.462 screenresolution[1633:7514] starting screenresolution argv=screenresolution get 02:49:55 INFO - 2017-06-13 02:49:55.465 screenresolution[1633:7514] Display 0: 1600x1200x32@60 02:49:55 INFO - 2017-06-13 02:49:55.471 screenresolution[1634:7516] starting screenresolution argv=screenresolution list 02:49:55 INFO - Available Modes on Display 0 02:49:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:55 INFO - Intel Iris: 02:49:55 INFO - Chipset Model: Intel Iris 02:49:55 INFO - Type: GPU 02:49:55 INFO - Bus: Built-In 02:49:55 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:55 INFO - Vendor: Intel (0x8086) 02:49:55 INFO - Device ID: 0x0a2e 02:49:55 INFO - Revision ID: 0x0009 02:49:55 INFO - Displays: 02:49:55 INFO - Display: 02:49:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:55 INFO - Display Serial Number: 1600x1200 60 02:49:55 INFO - Main Display: Yes 02:49:55 INFO - Mirror: Off 02:49:55 INFO - Online: Yes 02:49:55 INFO - Rotation: Supported 02:49:55 INFO - Return code: 0 02:49:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:49:55 INFO - Python 2.7.3 02:49:55 INFO - Return code: 0 02:49:55 INFO - grabbing minidump binary from tooltool 02:49:55 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')]} 02:49:55 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 0x10b513d30>, '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 0x10b4ddcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b504be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:49:55 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 02:49:55 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 02:49:55 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 02:49:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:49:55 INFO - Return code: 0 02:49:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:49:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:49:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:49:55 INFO - ENV: RUST_BACKTRACE is now 1 02:49:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:49:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:49:55 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-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:49:55 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-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0293 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YU3xgden3H/Render', 02:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:55 INFO - 'HOME': '/Users/cltbld', 02:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:55 INFO - 'LOGNAME': 'cltbld', 02:49:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:49:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:55 INFO - 'MOZ_NO_REMOTE': '1', 02:49:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:49:55 INFO - 'NO_EM_RESTART': '1', 02:49:55 INFO - 'PAGER': '/bin/cat', 02:49:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:55 INFO - 'PWD': '/builds/slave/test', 02:49:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:55 INFO - 'RUST_BACKTRACE': '1', 02:49:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:49:55 INFO - 'SHELL': '/bin/bash', 02:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dKdnEtbaNu/Listeners', 02:49:55 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:55 INFO - 'USER': 'cltbld', 02:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:55 INFO - 'XPC_FLAGS': '0x0', 02:49:55 INFO - 'XPC_SERVICE_NAME': '0', 02:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:49:56 INFO - mozversion application_buildid: 20170613010550 02:49:56 INFO - mozversion application_changeset: 9f97bc5242590a32204233597ca24ad39ff42847 02:49:56 INFO - mozversion application_display_name: Firefox 02:49:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:49:56 INFO - mozversion application_name: Firefox 02:49:56 INFO - mozversion application_remotingname: firefox 02:49:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:49:56 INFO - mozversion application_vendor: Mozilla 02:49:56 INFO - mozversion application_version: 55.0 02:49:56 INFO - mozversion platform_buildid: 20170613010550 02:49:56 INFO - mozversion platform_changeset: 9f97bc5242590a32204233597ca24ad39ff42847 02:49:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:49:56 INFO - mozversion platform_version: 55.0 02:49:56 INFO - using testdate: 1497347396 02:49:56 INFO - actual date: 1497347396 02:49:56 INFO - starting webserver on 'localhost:49229' 02:49:56 INFO - SUITE-START | Running 2 tests 02:49:56 INFO - TEST-START | dromaeo_css 02:49:56 INFO - operating with platform_type : mac_ 02:49:56 INFO - Initialising browser for dromaeo_css test... 02:49:56 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpmFPXEo/profile 02:49:56 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 02:50:29 INFO - TEST-INFO | 1644: killed by SIGTERM 02:50:29 INFO - Browser initialized. 02:50:29 INFO - Running cycle 1/1 for dromaeo_css test... 02:50:29 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpmFPXEo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:50:30 INFO - PID 1654 | 2017-06-13 02:50:30.918 plugin-container[1655:8050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.tsm.portname' 02:50:30 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:30 INFO - PID 1654 | 2017-06-13 02:50:30.918 plugin-container[1655:8050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 02:50:30 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:30 INFO - PID 1654 | 2017-06-13 02:50:30.918 plugin-container[1655:8050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:50:41 INFO - PID 1654 | RSS: Main: 184877056 02:50:41 INFO - PID 1654 | 02:53:43 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 02:53:45 INFO - PID 1654 | RSS: Main: 198725632 02:53:45 INFO - PID 1654 | 02:56:47 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 02:56:47 INFO - PID 1654 | RSS: Main: 210550784 02:56:47 INFO - PID 1654 | 02:59:49 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 02:59:50 INFO - PID 1654 | RSS: Main: 212492288 02:59:50 INFO - PID 1654 | 03:02:51 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 03:02:52 INFO - PID 1654 | RSS: Main: 215425024 03:02:52 INFO - PID 1654 | 03:05:55 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 03:05:56 INFO - PID 1654 | RSS: Main: 218230784 03:05:56 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 03:08:57 INFO - PID 1654 | RSS: Main: 215252992 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | __start_tp_report 03:08:57 INFO - PID 1654 | _x_x_mozilla_page_load 03:08:57 INFO - PID 1654 | _x_x_mozilla_page_load_details 03:08:57 INFO - PID 1654 | |i|pagename|runs| 03:08:57 INFO - PID 1654 | |0;dojo.html;3723.9945214792024;3817.064819119316;3835.7314987950845;3841.1549459118983;3876.224755048991;6378.234611846579;6408.7756928507515;6409.294977552466;6426.325235850239;6434.517411194156;6571.737130514785;6574.934250657492;6593.868122637545;6603.735850565971;6604.504662150346;5726.3414707308675;5796.391378905218;5857.560682948774;5962.135490353896;6052.697365131748;5756.338021127582;5870.442307980735;5893.55798315126;5986.580939334249;6015.6992150392525;4554.612857907082;5384.300040994684;5594.13290939905;5818.44724751148;5881.588288819763;5886.911696324559;5889.234399528077;5916.704164791743;5920.79277225295;5941.881162376728;5738.799142030009;5826.883462330729;5843.824685259449;5855.151003104526;5883.646981181143;6536.41172294491;6549.181352330958;6563.704633291513;6573.506986975948;6618.669066546653;6893.965530172317;7002.334778196063;7054.823629409255;7121.643917804089;7199.640017999079;6135.07955838608;7234.855302893965;7249.782506524813;7280.74517391894;7304.233055529146;5977.342492325841;6450.967845144848;6641.601503909781;6697.363750443748;6729.091572637729;6213.782517611862;7442.851142977219;7481;7481.9251807482315;7497.962510187414;6763.898541521881;6838.589684618939;6866.896996545056;6963.059986901703;7043.471739619446;7198.640067996579;7253.963730181315;7254.782356529313;7286.927130728731;7291.562506249642;7148.142222933281;7156.713731450788;7160.498765086394;7167.89248161278;7178.28217178278;7152.606606636581;7161.67772450241;7164.534305270141;7185.461090418239;7191.604461754549;5551.472610102034;5609.49514543693;5720.342160651495;5727.427257274239;5746.511546518509;4974.427940786856;4989.027139707722;5036.84889453317;5054.115529782273;5057.342545469065;5594.104943209149;6584.9012264816065;6586.670666466657;6651.63416012124;6765.086713293741;7212.3148300911325;7244.637768111679;7283.271672832674;7284.599347035858;7303.671334789948;6758.324167583301;6765;6783.62690052042;6783.830404239933;6786.728530858711;6166.075088736636;6295.842603934938;6346.58747181441;6351.777687780906;6379.617222966637;6817.659117044227;7433.962830185815;7496.962515187389;7504;7509.474336796372;5791.797287094932;7000.719971201095;7057.082579264663;7109.680064397011;7289.671964761705;7128.786136415916;7465.141508726566;7534.171241163358;7552.244775522624;7559.886601700869;7178.856422871618;7180.138383394026;7181.389581885389;7196;7198.604076775828;7325.6337183141695;7330.486865919334;7353.632318384166;7364.5581265124265;7369.631518424165;6824.7952561423235;6889.207741109636;6913.412359949461;6953.79138625842;7082.645867706491;6943.79168624942;6959.860802784017;7015.649217539205;7016.087908571853;7041.929580704127;6121.418465245794;6629.839778038728;6887.965560172167;6897.655117244018;6913.75801846933;7027.297270272932;7029.26192749754;7035.894461582978;7043.506954513135;7065.6113913735235;6626.171728533934;6697.12937318165;6721.966390168213;6787.287334829772;6826.761063362957 03:08:57 INFO - PID 1654 | |1;ext.html;774.9845003099938;808.8139727862591;856.7258477287263;875.2341701011619;886.5788750343586;38281.61718382815;39171.41242881359;39229.2154156917;39242.018949526275;39308.21383572327;32805.835970820175;38590.84227473173;38956.61043389565;39262.8036859816;39317;6673.833154171136;7821.37429005681;7895.921040789604;7964.362850971937;7990.720324788634;7844.372450203999;7941.523508589475;8273.33813294938;8494.490330580154;8511;7864.4101692373015;8087.867698522212;8502.319814414832;8562.272206862424;8573.95713021434;8451.197136272045;8480.74557763265;8496.362772792065;8538;8540.74377768668;8383.83232335356;8405.663773449054;8416.915830841735;8451.45065570742;8471.15288471154;36458.90623281306;36596.81701591502;36640;36669.89990300295;36739;3401.489776533515;3512.0868574170645;3674.375356189454;3679.0618392310057;3680.1719613087107;30230;30297.69702302971;30306.696933030827;30329.393412131856;30416.54375184374;3180.681931806801;3232.660570640096;3241.2383089973837;3251.6910893465083;3258.3483303339426;262732;265594.9999999981;265865;313491;314883.42558287294;39894.401583976265;43150.13699725988;43825;48064.5193548067;48096;50369.24446133311;50672;50743.98512029739;50781.23828142581;50818.47544573669;15776.211189440482;15876.285567149505;15877.285522151531;15887.761683574756;15907.125108119164;12918.289494077915;12949.223046617233;13027.609171724864;13029.022823288291;13058.934705326412;13562.186268823902;13587;13601.455941762417;13610.93194534021;13626.04617676773;5263.842084737464;6022.156898034279;6050.485708714808;6056.545759068087;6110.26676798787;942.1755963531909;952.6522819171091;977.3891317926297;1014.8832884218261;1015.8222311095528;23044.65433018506;23479.41301467443;23490.64764028541;23931.9657829083;24002.159924402564;1012.8959434216592;1047.5076713944586;1129.6836885672026;1177.8174382970483;1180.5218886351042;12056.819147712791;12376.319302438453;12407.25556466615;12477.875221247852;12571;345733;346997;347059;347223;348387.2580637081;53839;53840;53846.7307663467;54154.916901661745;54216.45783542193;1490.4336352186099;1567.1145802621254;1571.9371225150867;1572.9921350393174;1584.5246426072133;22364.88817555902;22651;23114.53770924606;23127.421814454774;24085;29955;36633.63366366302;36918.44622330721;36925.815370922974;36926.446103307935;302491;303593;305161;305356;305571;32156.356872862878;32355.51466728048;32506.34987300288;33567;33717.49423758694;43318.917027074574;44499.77750111229;44571.55428445674;44619.43831965866;44781.77609111933;28066.017689380773;28404.715952841034;29604.2025268727;29659.406811864075;30837.691623083483;29678.703212967594;30995.535066973564;31278.687213127578;31331.37337253288;31466;35554.8222258887;36011.45982810207;37720;37746.62253377541;37781.05547361338;27717;28820.84716611312;29864.552031719108;31852.840735796173;31882;23018.884905575364;24314.63528047116;24632.753672463044;24770.380740481633;24819.751802482468 03:08:57 INFO - PID 1654 | |2;jquery.html;514.9819756308528;533.7464704265475;534.644461433147;538.8410418926417;543.1364131031659;23289.534209315803;24061.27816165518;24130.27609171723;24131.15540956067;24139.51720965582;24955.500889982188;27442.5883611746;27592.17223483294;27677.861610691918;27792.16623501293;6923.653817309152;7066.752663656747;7103.25415831338;7109.715611375539;7112.4665650076195;7057.329553692391;7083.574985500861;7083.85832283354;7099.85800283994;7109.111361079865;6034.396560343975;6403.903941440859;6676.232233293161;6706.966465167668;6738.52830301879;6599.703013364386;6611.537192396534;6645.800625981228;6662.933370666304;6665.766698165589;6117.877642447126;6129.632222066645;6147.170132032208;6164.630122192682;6168.259808822925;30865;32123.35753284921;32488.837555812304;32531.837340813145;32908.01275961701;2310.3415526575013;2363.3382652857226;2379.6906402167606;2380.4405964598304;2384.141708984764;20164.49588760278;20705.68941465879;20719.27482538104;20725.378238652866;20750.169993200252;1989.293800700745;2291.6562515622622;2331.790138887491;2341.590221711194;2354.176038386568;216534;218297;221838;222356.77643223683;225538.87230563906;13574.59276221715;14302.713945721027;15921.442749503713;16285.430009949594;16972.575685607957;21980.791056492046;22198.55602887933;22239.55520889573;22268.99789509476;22311.661300322034;10976.506057227443;11003.449827508754;11113.777724445466;11171.83242251367;11234;10331.638392656372;10337.896621033844;10379.688609341732;10382.117520010883;10392.116670083127;9204.723858284262;9706.272029597809;9724;9881.703548893545;9904.504774761233;2815.211740712604;2822.520171570838;2834.5464725643797;2840.7331568696163;2960.57071724603;941.3598752848129;952.7237101240701;960.5053397500293;1010.9140723038624;1013.3717095400899;17621.823781762276;22403.55192896133;22552.436189095402;23146.421339466313;23370.948307326213;907.2424525521131;996.5963784667222;1007.6372505897871;1055.9736006599896;1056.2870062707796;11142.665720028412;11559.595414160633;11571.132165087653;14877.553673389815;15963.44127955516;607520;629506.8524657347;637375;645371;650398;176400;179208.10395947937;179351.10324448295;180458.09770951324;184430.0778496099;1154.1689983212075;1224.1492162946668;1306.4251729239104;1318.973620527584;1320.8216890719823;24501.754982449947;24983.50032999366;25691;25894.87052564725;25979.350516237246;74099.25900740923;76664;78911;79412.60293698494;79513.60243198978;102419.48790256299;102647.46028809724;106824.46587767011;106999;108169.45915270373;12032.09759268041;12258.080643951918;12442.688932776753;12446.937765311115;12463.065270104596;19813.405597832087;19943;19957.301494447624;20044.799552004293;20084.096215670037;16003.439879604159;16005.839941600902;16161.27274272684;16183.838161618232;16246.431374901822;13756.793648095487;13927.860721392657;14083.436662533384;14735.705285894437;14852.886033547311;16072.437464689148;16276.27860157228;16701.49895503137;17038;17059.91470042642;12484.687882803002;12603;12641.3679316032;12657.87342126567;12662.050346224254;16069.678606427573;16212.918935405247;16343.83656163423;16483.175841208133;16549.83450165483 03:08:57 INFO - PID 1654 | |3;mootools.html;1230.2495477758569;1348.5684580934098;1478.7338279109756;1492.3433689176761;1496.8278647955485;13997;14606.853931460682;14627.707445851076;14632.34154463049;14657.486987955424;14341.713165736679;14607.707845843104;14676.486322978697;14732.779008314883;14767.92616036921;2971.2423332050353;2990.8953186638473;3031.863566139518;3031.924201894948;3035.362573859481;2934.970650293491;2955.1577800326913;2964.347843474432;2968.584398184256;2975.3751712140474;2826.915192544227;2943.499605067144;2969.6584892737396;2985.999690103818;3048.725614694677;3023.697630236981;3026.1980575147604;3051.313454472748;3061.0510741670155;3067.4631939410624;2958.304798372381;2969.465496210681;2979.031814660244;2979.582858399804;2999.5950546676354;14232;14243.572692819233;14354.851611871025;14365.497207597788;14377;6571.507136964699;7787.18234585366;8152.836943261102;8230.382721295926;8348.914641096679;4659.114768194032;5019.322391477177;5128.102582048164;5173.094708426048;5191.8182863599595;1482.948096816617;1485.9182744949023;1493.6340596553914;1510.6903084867686;1517.3854588891516;21400.78599214019;21559.892200539052;21561.167300779452;21571;21611.567768644538;6868.179932317939;6929.3070692930305;6953.026576279325;7076.186238582526;7109.82225444368;14912.62718432048;15015.699686006219;15027.323770430137;15032;15036.022658527161;7808.297253247121;7909.1299957004685;7954.164812694699;7966.243206895336;7968.0836703779805;7236.493445458873;7274.236205198484;7310.488265821447;7371.741989030358;7387;5184.611154163453;5359.946400536022;5428.809991650273;5437.13005919051;5440.755166017539;1626.8454496822783;1629.380835282585;1630.070859610011;1631.4126914310839;1660.709375859244;802.1216767639426;808.9676412943417;824.1675907333636;830.3357314148657;849.9630450849953;6718.002788340929;6886.48351373649;6931.064306318583;8819.206271435602;8861.689840855539;1005;1013.2096964367805;1034.798214348195;1050.6480329089688;1066.1630619963291;4310.418093557392;4787.664863459594;5129.153689641167;5148.819791307361;5296.92054619181;18747.437576872715;19091.04544772756;19250.614987700446;19393.224271029;19449.22203111888;16435;18535.53661158455;19532.31636892702;19582.90208548948;19656.410307690792;10137.949310253402;10167.4916254187;10212.336198147097;10231.232657550863;10300.896991030144;6343.682815859096;7998.3201427879285;8398.370122240978;8429.915700842914;8525.701600444203;17424.867383620025;17827.19777609985;18076.638467230845;18191.545211369823;18241.270349186416;14443.013052672599;17871.642567148323;18464.63070738551;18629.81370186335;18669.43991680252;10457.633982810565;11319.773604528027;11406.429678515875;11511.079113671054;11579.652610421701;12927.612171634866;13106.737865242832;13108.868911310765;13117.409716562586;13130.2778347188;9254.4910029949;10424.530896109845;10504.684859454228;10562.735931601772;10620.256582039183;9437.811243775224;9526.714198574055;10069.647562335284;10561;10760.838587421038;9544.42733435996;9618.951905240428;9769.85345219808;9772.755681108029;9782;6224.533160012927;6753.797386078426;6816.796105830252;7045.2954704531185;7193.892091618734;6960.825979350557;7117.928820711727;7304.452166087671;7413.258674132544;7420.5547667140145 03:08:57 INFO - PID 1654 | |4;prototype.html;464.9770504889245;479.49652864492276;486.1468123443356;490.70312460961094;500.9949900500994;5378.946210537893;5459.508644222019;6573.86852262955;6595.868082638356;6660.367265109813;6238.4385405313515;6345.52408569358;6456.644884531361;6469.70886310116;6481.31946145655;4332.65338772899;4345.587169218919;4615.792289346988;4901.240307752304;4905.484924082964;4885.633577481685;4892.315075889361;4894.804207831682;4932.975335123338;4933.284673722308;4638.026014536935;4858.757062146896;4869.9269510957365;4875.63432742544;4894.486078961711;4681.8829529261875;4867.8783030424165;4888.902221955559;4896.46138924718;4920.532549407801;3774.245150969817;4277.700560960734;4317.805698743564;4320.503142138631;4325.567443255681;5082.059818933472;6140.9385906140815;6229.193533875216;6287.937120628781;6337.746490140435;5570.916436253459;5751.568632352591;5799.565032622528;5817.680027598481;5832.6792026438525;6985.755498557577;7002.739506888758;7037.22590515043;7064.540804847668;7067.399271061966;4317.546657600969;4440.600345968878;4684.414448193976;4942.555170034678;4963.205887058053;6176.722047507873;6380.521460890405;7165.456263026603;7479.850402991964;7507.737229196952;4190.182914331737;4865.861519320089;5032.616030591616;5049.267856160836;5061.670991385548;4592.816287348535;4983.526564976321;5171.069207542679;5178.689278643294;5179.870503237374;5092.719900405513;5762.789814138994;5784.94215057844;5802.56292357211;5839.299284085937;7067.363937245673;7070.7525236616475;7077.893831592531;7085.681144348517;7093.32613401726;1353.9574527613681;1390.9443622254996;1463.9853601464063;1744.842964133234;1766.655502177089;1173.7183076061683;1175.8177482490057;1180.1090176916546;1187.7446349035;1204.163106640876;5237.423883372826;5412.242286079952;6213.720382582705;6571.67141642916;6681;6800.897986530206;7172.964135179291;7195.424366050808;7204.046341000165;7276.527025743309;5688.829335119953;5698;6583.012548117821;6615.569987950767;6678.6326752028135;3129.890453834105;3268.836558172082;3731.738778285548;3749.2126653402547;3785.734998550075;6542.967285163544;7350.038723861372;7444.739434119888;7497.66260518278;7526.5860377678655;6041.72812223451;6379.936200638027;7241.710331586783;7471.364933980563;7482.176960534337;7433.182349941502;7461.515001524884;7463.328300452985;7478.887816682754;7503.924960750432;5971.984762590283;6387.712552935222;6858.657067146523;6911.239763626098;7231.602261875647;5912.586118971631;6192.783252586138;7051.1186101737285;7096.396806271318;7100.147982242164;6132.78535251264;6927.856903611049;7472.589007604416;7495.201151723656;7501.2123727007875;4446.355278484537;5049.752859977421;5055.848324550269;5059.367579052619;5064.898702026013;7193.964030179815;7278.381337586365;7280.817979550554;7285.927140728525;7299.452541059336;6727.428168605527;6737.273317679933;6896.896546551906;6897.930820722796;7057.894131588133;5476.644018138808;5901.173835663097;6896.827499337693;6988.091548098715;7044.084269044992;6266.68666566679;6873.862522749617;6874.381305682513;6898.172219333712;6910.896336554855;6119.724612392364;7011.1586609607175;7015.579065256101;7044.084269044992;7044.647767611496;5436.782528698808;5902.881942361215;6181.443670069715;6876.071730316343;6884.65576721172 03:08:57 INFO - PID 1654 | |5;yui.html;741.1883987034199;825.0923983618021;845.1379592815329;851.6678495386797;890.0565400675283;17849;17895.821041789593;18023.09884505776;18237.452876413696;18368.35710750124;13763.449462021532;15471.767923481157;15499.69000619987;17950.910245448755;19817.504562385948;1040.2926010313004;1050.406520316019;1050.4432650695144;1051.1590727418072;1057.376148072637;1061.9946900265488;1066.4667666166918;1067.9199060070487;1069.6256310291374;1072.7639919217759;1118.4351902289332;1134.7787181499612;1135.2280449294476;1136.5681041204332;1137.7383201863577;1055.0241027049985;1098.3080659184743;1099.5546803544537;1104.4588151805558;1113.8162203236457;799.9800004999921;803.3894240377297;805.1103530598663;807.2290962131209;807.8061265296345;18315.633687326314;18426.262949482003;18669.906650466663;18762.343317983807;18793.77840440367;18202.726959095624;18608.06959652012;18768;18907.527311817175;18913.24347026117;15693.921530392276;15770.448034318859;15846.683066338725;15868.761968570365;15870.12714300713;3825.598312177209;3841.3469710149343;3845.9615403845883;3855.4602355670227;3863.1114843586133;143297.2835135828;151387.90518972694;155921;158556;164584;14103.224322662352;14423.206723630301;14910.403583856742;15132.167730774912;15243.237838108273;28629;28659.996900108395;28871.711282886903;29035.274118147216;29198.27004324909;8589.527575983257;8638.222559969507;8640.567971601395;8644.697435589724;8648.524331161847;4031;4056.411820286101;4102.897427564335;4103.117829666636;4118.032262418329;4846.200376937836;4936.234883593049;4939.481354457802;4941.703497790072;4952.4057113146655;1021.1779517488493;1082.220801023262;1084.7613525024483;1091.3888222595372;1093.6445655161945;659.5844617890698;680.9727610895509;686.8935315026179;711.0933559711409;714.2821464428216;11590.014848737952;11634.243774154653;12652.746945061048;13058.804117938238;13097.934510327577;610.2920612089954;655.7672026430644;673.3569441183657;683.1835956032593;707.2538471912183;5297.549708274762;5744.138379243147;5757.712114394348;5801.158831969426;5943.286805583358;140780;143899;147088.5291147096;147847.52152478337;150330;106751;121289;124234;131046;132845;80282.5985870067;91449;109288;109619.45190273998;112516.87483125228;11605.89743974321;11683.707907302045;11886.108541859223;12537.749245015231;12750.107492475438;43572.25710971653;44848.32727509025;44957;45007.54992450034;49041;62148;77529.22470775446;78517;79512.01219969547;82834.5858270729;18296.72554911704;18329.725054124465;18381.264749409875;18582.58426404559;18676.906615466836;18457.446276611725;19134.138963746387;19421.902890485457;19912;20058.59882802365;17886.46340609784;18343.26626934908;18681.719774203128;18721.063946802333;18942.810571894104;14766.704665906836;17902.28390864403;18266.543336416165;18311.633767324845;18422.539436514195;17480.47558573245;17591.82408175953;17792.288308468032;18078.09609519545;18556.44330670082;12051.337176455028;12422.503099876265;12524.624261272176;12736.74526509483;12998.870011299767;12588.811167832673;13474.19154850712;14750.926245368704;14798.704025919205;14828.258587070388 03:08:57 INFO - PID 1654 | __end_tp_report 03:08:57 INFO - PID 1654 | __start_cc_report 03:08:57 INFO - PID 1654 | _x_x_mozilla_cycle_collect,386 03:08:57 INFO - PID 1654 | __end_cc_report 03:08:57 INFO - PID 1654 | __startTimestamp1497348537947__endTimestamp 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | ------- Summary: start ------- 03:08:57 INFO - PID 1654 | Number of tests: 6 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6567.24 Median:6783.83 stddev:805.57 (11.9%) stddev-sans-first:776.55 03:08:57 INFO - PID 1654 | Values: 3724.0 3817.1 3835.7 3841.2 3876.2 6378.2 6408.8 6409.3 6426.3 6434.5 6571.7 6574.9 6593.9 6603.7 6604.5 5726.3 5796.4 5857.6 5962.1 6052.7 5756.3 5870.4 5893.6 5986.6 6015.7 4554.6 5384.3 5594.1 5818.4 5881.6 5886.9 5889.2 5916.7 5920.8 5941.9 5738.8 5826.9 5843.8 5855.2 5883.6 6536.4 6549.2 6563.7 6573.5 6618.7 6894.0 7002.3 7054.8 7121.6 7199.6 6135.1 7234.9 7249.8 7280.7 7304.2 5977.3 6451.0 6641.6 6697.4 6729.1 6213.8 7442.9 7481.0 7481.9 7498.0 6763.9 6838.6 6866.9 6963.1 7043.5 7198.6 7254.0 7254.8 7286.9 7291.6 7148.1 7156.7 7160.5 7167.9 7178.3 7152.6 7161.7 7164.5 7185.5 7191.6 5551.5 5609.5 5720.3 5727.4 5746.5 4974.4 4989.0 5036.8 5054.1 5057.3 5594.1 6584.9 6586.7 6651.6 6765.1 7212.3 7244.6 7283.3 7284.6 7303.7 6758.3 6765.0 6783.6 6783.8 6786.7 6166.1 6295.8 6346.6 6351.8 6379.6 6817.7 7434.0 7497.0 7504.0 7509.5 5791.8 7000.7 7057.1 7109.7 7289.7 7128.8 7465.1 7534.2 7552.2 7559.9 7178.9 7180.1 7181.4 7196.0 7198.6 7325.6 7330.5 7353.6 7364.6 7369.6 6824.8 6889.2 6913.4 6953.8 7082.6 6943.8 6959.9 7015.6 7016.1 7041.9 6121.4 6629.8 6888.0 6897.7 6913.8 7027.3 7029.3 7035.9 7043.5 7065.6 6626.2 6697.1 6722.0 6787.3 6826.8 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:45920.85 Median:346365.00 stddev:82339.38 (23.8%) stddev-sans-first:82500.59 03:08:57 INFO - PID 1654 | Values: 775.0 808.8 856.7 875.2 886.6 38281.6 39171.4 39229.2 39242.0 39308.2 32805.8 38590.8 38956.6 39262.8 39317.0 6673.8 7821.4 7895.9 7964.4 7990.7 7844.4 7941.5 8273.3 8494.5 8511.0 7864.4 8087.9 8502.3 8562.3 8574.0 8451.2 8480.7 8496.4 8538.0 8540.7 8383.8 8405.7 8416.9 8451.5 8471.2 36458.9 36596.8 36640.0 36669.9 36739.0 3401.5 3512.1 3674.4 3679.1 3680.2 30230.0 30297.7 30306.7 30329.4 30416.5 3180.7 3232.7 3241.2 3251.7 3258.3 262732.0 265595.0 265865.0 313491.0 314883.4 39894.4 43150.1 43825.0 48064.5 48096.0 50369.2 50672.0 50744.0 50781.2 50818.5 15776.2 15876.3 15877.3 15887.8 15907.1 12918.3 12949.2 13027.6 13029.0 13058.9 13562.2 13587.0 13601.5 13610.9 13626.0 5263.8 6022.2 6050.5 6056.5 6110.3 942.2 952.7 977.4 1014.9 1015.8 23044.7 23479.4 23490.6 23932.0 24002.2 1012.9 1047.5 1129.7 1177.8 1180.5 12056.8 12376.3 12407.3 12477.9 12571.0 345733.0 346997.0 347059.0 347223.0 348387.3 53839.0 53840.0 53846.7 54154.9 54216.5 1490.4 1567.1 1571.9 1573.0 1584.5 22364.9 22651.0 23114.5 23127.4 24085.0 29955.0 36633.6 36918.4 36925.8 36926.4 302491.0 303593.0 305161.0 305356.0 305571.0 32156.4 32355.5 32506.3 33567.0 33717.5 43318.9 44499.8 44571.6 44619.4 44781.8 28066.0 28404.7 29604.2 29659.4 30837.7 29678.7 30995.5 31278.7 31331.4 31466.0 35554.8 36011.5 37720.0 37746.6 37781.1 27717.0 28820.8 29864.6 31852.8 31882.0 23018.9 24314.6 24632.8 24770.4 24819.8 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:44551.74 Median:22357.61 stddev:110386.83 (493.7%) stddev-sans-first:110646.98 03:08:57 INFO - PID 1654 | Values: 515.0 533.7 534.6 538.8 543.1 23289.5 24061.3 24130.3 24131.2 24139.5 24955.5 27442.6 27592.2 27677.9 27792.2 6923.7 7066.8 7103.3 7109.7 7112.5 7057.3 7083.6 7083.9 7099.9 7109.1 6034.4 6403.9 6676.2 6707.0 6738.5 6599.7 6611.5 6645.8 6662.9 6665.8 6117.9 6129.6 6147.2 6164.6 6168.3 30865.0 32123.4 32488.8 32531.8 32908.0 2310.3 2363.3 2379.7 2380.4 2384.1 20164.5 20705.7 20719.3 20725.4 20750.2 1989.3 2291.7 2331.8 2341.6 2354.2 216534.0 218297.0 221838.0 222356.8 225538.9 13574.6 14302.7 15921.4 16285.4 16972.6 21980.8 22198.6 22239.6 22269.0 22311.7 10976.5 11003.4 11113.8 11171.8 11234.0 10331.6 10337.9 10379.7 10382.1 10392.1 9204.7 9706.3 9724.0 9881.7 9904.5 2815.2 2822.5 2834.5 2840.7 2960.6 941.4 952.7 960.5 1010.9 1013.4 17621.8 22403.6 22552.4 23146.4 23370.9 907.2 996.6 1007.6 1056.0 1056.3 11142.7 11559.6 11571.1 14877.6 15963.4 607520.0 629506.9 637375.0 645371.0 650398.0 176400.0 179208.1 179351.1 180458.1 184430.1 1154.2 1224.1 1306.4 1319.0 1320.8 24501.8 24983.5 25691.0 25894.9 25979.4 74099.3 76664.0 78911.0 79412.6 79513.6 102419.5 102647.5 106824.5 106999.0 108169.5 12032.1 12258.1 12442.7 12446.9 12463.1 19813.4 19943.0 19957.3 20044.8 20084.1 16003.4 16005.8 16161.3 16183.8 16246.4 13756.8 13927.9 14083.4 14735.7 14852.9 16072.4 16276.3 16701.5 17038.0 17059.9 12484.7 12603.0 12641.4 12657.9 12662.1 16069.7 16212.9 16343.8 16483.2 16549.8 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:8734.52 Median:2956.73 stddev:5820.56 (196.9%) stddev-sans-first:5809.57 03:08:57 INFO - PID 1654 | Values: 1230.2 1348.6 1478.7 1492.3 1496.8 13997.0 14606.9 14627.7 14632.3 14657.5 14341.7 14607.7 14676.5 14732.8 14767.9 2971.2 2990.9 3031.9 3031.9 3035.4 2935.0 2955.2 2964.3 2968.6 2975.4 2826.9 2943.5 2969.7 2986.0 3048.7 3023.7 3026.2 3051.3 3061.1 3067.5 2958.3 2969.5 2979.0 2979.6 2999.6 14232.0 14243.6 14354.9 14365.5 14377.0 6571.5 7787.2 8152.8 8230.4 8348.9 4659.1 5019.3 5128.1 5173.1 5191.8 1482.9 1485.9 1493.6 1510.7 1517.4 21400.8 21559.9 21561.2 21571.0 21611.6 6868.2 6929.3 6953.0 7076.2 7109.8 14912.6 15015.7 15027.3 15032.0 15036.0 7808.3 7909.1 7954.2 7966.2 7968.1 7236.5 7274.2 7310.5 7371.7 7387.0 5184.6 5359.9 5428.8 5437.1 5440.8 1626.8 1629.4 1630.1 1631.4 1660.7 802.1 809.0 824.2 830.3 850.0 6718.0 6886.5 6931.1 8819.2 8861.7 1005.0 1013.2 1034.8 1050.6 1066.2 4310.4 4787.7 5129.2 5148.8 5296.9 18747.4 19091.0 19250.6 19393.2 19449.2 16435.0 18535.5 19532.3 19582.9 19656.4 10137.9 10167.5 10212.3 10231.2 10300.9 6343.7 7998.3 8398.4 8429.9 8525.7 17424.9 17827.2 18076.6 18191.5 18241.3 14443.0 17871.6 18464.6 18629.8 18669.4 10457.6 11319.8 11406.4 11511.1 11579.7 12927.6 13106.7 13108.9 13117.4 13130.3 9254.5 10424.5 10504.7 10562.7 10620.3 9437.8 9526.7 10069.6 10561.0 10760.8 9544.4 9619.0 9769.9 9772.8 9782.0 6224.5 6753.8 6816.8 7045.3 7193.9 6960.8 7117.9 7304.5 7413.3 7420.6 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5628.12 Median:6136.86 stddev:1741.47 (28.4%) stddev-sans-first:1702.69 03:08:57 INFO - PID 1654 | Values: 465.0 479.5 486.1 490.7 501.0 5378.9 5459.5 6573.9 6595.9 6660.4 6238.4 6345.5 6456.6 6469.7 6481.3 4332.7 4345.6 4615.8 4901.2 4905.5 4885.6 4892.3 4894.8 4933.0 4933.3 4638.0 4858.8 4869.9 4875.6 4894.5 4681.9 4867.9 4888.9 4896.5 4920.5 3774.2 4277.7 4317.8 4320.5 4325.6 5082.1 6140.9 6229.2 6287.9 6337.7 5570.9 5751.6 5799.6 5817.7 5832.7 6985.8 7002.7 7037.2 7064.5 7067.4 4317.5 4440.6 4684.4 4942.6 4963.2 6176.7 6380.5 7165.5 7479.9 7507.7 4190.2 4865.9 5032.6 5049.3 5061.7 4592.8 4983.5 5171.1 5178.7 5179.9 5092.7 5762.8 5784.9 5802.6 5839.3 7067.4 7070.8 7077.9 7085.7 7093.3 1354.0 1390.9 1464.0 1744.8 1766.7 1173.7 1175.8 1180.1 1187.7 1204.2 5237.4 5412.2 6213.7 6571.7 6681.0 6800.9 7173.0 7195.4 7204.0 7276.5 5688.8 5698.0 6583.0 6615.6 6678.6 3129.9 3268.8 3731.7 3749.2 3785.7 6543.0 7350.0 7444.7 7497.7 7526.6 6041.7 6379.9 7241.7 7471.4 7482.2 7433.2 7461.5 7463.3 7478.9 7503.9 5972.0 6387.7 6858.7 6911.2 7231.6 5912.6 6192.8 7051.1 7096.4 7100.1 6132.8 6927.9 7472.6 7495.2 7501.2 4446.4 5049.8 5055.8 5059.4 5064.9 7194.0 7278.4 7280.8 7285.9 7299.5 6727.4 6737.3 6896.9 6897.9 7057.9 5476.6 5901.2 6896.8 6988.1 7044.1 6266.7 6873.9 6874.4 6898.2 6910.9 6119.7 7011.2 7015.6 7044.1 7044.6 5436.8 5902.9 6181.4 6876.1 6884.7 03:08:57 INFO - PID 1654 | 03:08:57 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:26556.48 Median:18304.18 stddev:40433.08 (220.9%) stddev-sans-first:40500.05 03:08:57 INFO - PID 1654 | Values: 741.2 825.1 845.1 851.7 890.1 17849.0 17895.8 18023.1 18237.5 18368.4 13763.4 15471.8 15499.7 17950.9 19817.5 1040.3 1050.4 1050.4 1051.2 1057.4 1062.0 1066.5 1067.9 1069.6 1072.8 1118.4 1134.8 1135.2 1136.6 1137.7 1055.0 1098.3 1099.6 1104.5 1113.8 800.0 803.4 805.1 807.2 807.8 18315.6 18426.3 18669.9 18762.3 18793.8 18202.7 18608.1 18768.0 18907.5 18913.2 15693.9 15770.4 15846.7 15868.8 15870.1 3825.6 3841.3 3846.0 3855.5 3863.1 143297.3 151387.9 155921.0 158556.0 164584.0 14103.2 14423.2 14910.4 15132.2 15243.2 28629.0 28660.0 28871.7 29035.3 29198.3 8589.5 8638.2 8640.6 8644.7 8648.5 4031.0 4056.4 4102.9 4103.1 4118.0 4846.2 4936.2 4939.5 4941.7 4952.4 1021.2 1082.2 1084.8 1091.4 1093.6 659.6 681.0 686.9 711.1 714.3 11590.0 11634.2 12652.7 13058.8 13097.9 610.3 655.8 673.4 683.2 707.3 5297.5 5744.1 5757.7 5801.2 5943.3 140780.0 143899.0 147088.5 147847.5 150330.0 106751.0 121289.0 124234.0 131046.0 132845.0 80282.6 91449.0 109288.0 109619.5 112516.9 11605.9 11683.7 11886.1 12537.7 12750.1 43572.3 44848.3 44957.0 45007.5 49041.0 62148.0 77529.2 78517.0 79512.0 82834.6 18296.7 18329.7 18381.3 18582.6 18676.9 18457.4 19134.1 19421.9 19912.0 20058.6 17886.5 18343.3 18681.7 18721.1 18942.8 14766.7 17902.3 18266.5 18311.6 18422.5 17480.5 17591.8 17792.3 18078.1 18556.4 12051.3 12422.5 12524.6 12736.7 12998.9 12588.8 13474.2 14750.9 14798.7 14828.3 03:08:57 INFO - PID 1654 | -------- Summary: end -------- 03:08:57 INFO - PID 1654 | 03:08:58 INFO - TEST-INFO | 1654: exit 0 03:08:58 INFO - TEST-OK | dromaeo_css | took 1142362ms 03:08:58 INFO - TEST-START | kraken 03:08:58 INFO - operating with platform_type : mac_ 03:08:58 INFO - Initialising browser for kraken test... 03:08:58 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpRhamME/profile 03:08:58 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 03:09:31 INFO - TEST-INFO | 1664: killed by SIGTERM 03:09:31 INFO - Browser initialized. 03:09:31 INFO - Running cycle 1/1 for kraken test... 03:09:31 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpRhamME/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:09:32 INFO - PID 1667 | 2017-06-13 03:09:32.862 plugin-container[1668:10559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 03:09:32 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:09:32 INFO - PID 1667 | 2017-06-13 03:09:32.863 plugin-container[1668:10559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 03:09:32 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:09:32 INFO - PID 1667 | 2017-06-13 03:09:32.863 plugin-container[1668:10559] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:09:42 INFO - PID 1667 | RSS: Main: 185929728 03:09:42 INFO - PID 1667 | 03:09:45 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 03:09:46 INFO - PID 1667 | RSS: Main: 198885376 03:09:46 INFO - PID 1667 | 03:09:49 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 03:09:49 INFO - PID 1667 | RSS: Main: 211959808 03:09:49 INFO - PID 1667 | 03:09:53 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 03:09:53 INFO - PID 1667 | RSS: Main: 215937024 03:09:53 INFO - PID 1667 | 03:09:55 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 03:09:56 INFO - PID 1667 | RSS: Main: 216666112 03:09:56 INFO - PID 1667 | 03:09:58 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:09:58 INFO - PID 1667 | RSS: Main: 218230784 03:09:58 INFO - PID 1667 | 03:10:01 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 03:10:02 INFO - PID 1667 | RSS: Main: 222429184 03:10:02 INFO - PID 1667 | 03:10:05 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 03:10:06 INFO - PID 1667 | RSS: Main: 222662656 03:10:06 INFO - PID 1667 | 03:10:09 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 03:10:09 INFO - PID 1667 | RSS: Main: 222711808 03:10:09 INFO - PID 1667 | 03:10:11 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:10:11 INFO - PID 1667 | RSS: Main: 222216192 03:10:11 INFO - PID 1667 | 03:10:13 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:10:14 INFO - PID 1667 | RSS: Main: 221990912 03:10:14 INFO - PID 1667 | 03:10:16 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:10:16 INFO - PID 1667 | RSS: Main: 222507008 03:10:16 INFO - PID 1667 | 03:10:19 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:10:20 INFO - PID 1667 | RSS: Main: 222343168 03:10:20 INFO - PID 1667 | 03:10:23 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:10:23 INFO - PID 1667 | RSS: Main: 222904320 03:10:23 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 03:10:25 INFO - PID 1667 | RSS: Main: 224391168 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | __start_tp_report 03:10:25 INFO - PID 1667 | _x_x_mozilla_page_load 03:10:25 INFO - PID 1667 | _x_x_mozilla_page_load_details 03:10:25 INFO - PID 1667 | |i|pagename|runs| 03:10:25 INFO - PID 1667 | |0;ai-astar;100;116;107;131;161;106;142;93;154;168 03:10:25 INFO - PID 1667 | |1;audio-beat-detection;138;110;129;145;109;151;127;95;161;132 03:10:25 INFO - PID 1667 | |2;audio-dft;199;264;205;195;198;200;194;198;203;199 03:10:25 INFO - PID 1667 | |3;audio-fft;70;86;72;160;60;66;83;90;137;56 03:10:25 INFO - PID 1667 | |4;audio-oscillator;69;76;120;72;70;74;105;68;69;107 03:10:25 INFO - PID 1667 | |5;imaging-gaussian-blur;91;92;91;91;96;113;90;104;102;92 03:10:25 INFO - PID 1667 | |6;imaging-darkroom;96;102;97;98;98;96;112;147;95;97 03:10:25 INFO - PID 1667 | |7;imaging-desaturate;89;111;90;92;91;98;77;130;144;115 03:10:25 INFO - PID 1667 | |8;json-parse-financial;49;49;48;50;51;51;49;51;51;49 03:10:25 INFO - PID 1667 | |9;json-stringify-tinderbox;53;55;63;73;54;51;63;74;75;78 03:10:25 INFO - PID 1667 | |10;stanford-crypto-aes;79;82;102;97;101;76;79;80;79;78 03:10:25 INFO - PID 1667 | |11;stanford-crypto-ccm;131;138;139;126;143;144;138;154;149;162 03:10:25 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;181;184;171;173;207;175;177;175;171;172 03:10:25 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;65;65;66;64;65;66;63;62;64;61 03:10:25 INFO - PID 1667 | __end_tp_report 03:10:25 INFO - PID 1667 | __start_cc_report 03:10:25 INFO - PID 1667 | _x_x_mozilla_cycle_collect,973 03:10:25 INFO - PID 1667 | __end_cc_report 03:10:25 INFO - PID 1667 | __startTimestamp1497348625810__endTimestamp 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | ------- Summary: start ------- 03:10:25 INFO - PID 1667 | Number of tests: 14 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:127.80 Median:148.00 stddev:27.18 (18.4%) stddev-sans-first:26.90 03:10:25 INFO - PID 1667 | Values: 100.0 116.0 107.0 131.0 161.0 106.0 142.0 93.0 154.0 168.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:141.50 stddev:20.47 (14.5%) stddev-sans-first:21.49 03:10:25 INFO - PID 1667 | Values: 138.0 110.0 129.0 145.0 109.0 151.0 127.0 95.0 161.0 132.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:205.50 Median:199.50 stddev:20.81 (10.4%) stddev-sans-first:21.94 03:10:25 INFO - PID 1667 | Values: 199.0 264.0 205.0 195.0 198.0 200.0 194.0 198.0 203.0 199.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:88.00 Median:71.00 stddev:34.14 (48.1%) stddev-sans-first:35.58 03:10:25 INFO - PID 1667 | Values: 70.0 86.0 72.0 160.0 60.0 66.0 83.0 90.0 137.0 56.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:83.00 Median:69.50 stddev:19.62 (28.2%) stddev-sans-first:20.15 03:10:25 INFO - PID 1667 | Values: 69.0 76.0 120.0 72.0 70.0 74.0 105.0 68.0 69.0 107.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:91.00 stddev:7.69 (8.4%) stddev-sans-first:7.92 03:10:25 INFO - PID 1667 | Values: 91.0 92.0 91.0 91.0 96.0 113.0 90.0 104.0 102.0 92.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:103.80 Median:96.50 stddev:15.97 (16.6%) stddev-sans-first:16.69 03:10:25 INFO - PID 1667 | Values: 96.0 102.0 97.0 98.0 98.0 96.0 112.0 147.0 95.0 97.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:103.70 Median:89.50 stddev:20.93 (23.4%) stddev-sans-first:21.52 03:10:25 INFO - PID 1667 | Values: 89.0 111.0 90.0 92.0 91.0 98.0 77.0 130.0 144.0 115.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.50 stddev:1.14 (2.2%) stddev-sans-first:1.17 03:10:25 INFO - PID 1667 | Values: 49.0 49.0 48.0 50.0 51.0 51.0 49.0 51.0 51.0 49.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:68.00 stddev:10.39 (15.3%) stddev-sans-first:10.24 03:10:25 INFO - PID 1667 | Values: 53.0 55.0 63.0 73.0 54.0 51.0 63.0 74.0 75.0 78.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:79.00 stddev:10.33 (13.1%) stddev-sans-first:10.70 03:10:25 INFO - PID 1667 | Values: 79.0 82.0 102.0 97.0 101.0 76.0 79.0 80.0 79.0 78.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:143.50 stddev:10.62 (7.4%) stddev-sans-first:10.43 03:10:25 INFO - PID 1667 | Values: 131.0 138.0 139.0 126.0 143.0 144.0 138.0 154.0 149.0 162.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:176.00 stddev:10.85 (6.2%) stddev-sans-first:11.48 03:10:25 INFO - PID 1667 | Values: 181.0 184.0 171.0 173.0 207.0 175.0 177.0 175.0 171.0 172.0 03:10:25 INFO - PID 1667 | 03:10:25 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:65.00 stddev:1.66 (2.6%) stddev-sans-first:1.73 03:10:25 INFO - PID 1667 | Values: 65.0 65.0 66.0 64.0 65.0 66.0 63.0 62.0 64.0 61.0 03:10:25 INFO - PID 1667 | -------- Summary: end -------- 03:10:25 INFO - PID 1667 | 03:10:26 INFO - TEST-INFO | 1667: exit 0 03:10:26 INFO - TEST-OK | kraken | took 88162ms 03:10:26 INFO - SUITE-END | took 1230s 03:10:27 INFO - Completed test suite (00:20:30) 03:10:27 INFO - javascript benchmark 03:10:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8735.021992012898, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3723.9945214792024, 3817.064819119316, 3835.7314987950845, 3841.1549459118983, 3876.224755048991, 6378.234611846579, 6408.7756928507515, 6409.294977552466, 6426.325235850239, 6434.517411194156, 6571.737130514785, 6574.934250657492, 6593.868122637545, 6603.735850565971, 6604.504662150346, 5726.3414707308675, 5796.391378905218, 5857.560682948774, 5962.135490353896, 6052.697365131748, 5756.338021127582, 5870.442307980735, 5893.55798315126, 5986.580939334249, 6015.6992150392525, 4554.612857907082, 5384.300040994684, 5594.13290939905, 5818.44724751148, 5881.588288819763, 5886.911696324559, 5889.234399528077, 5916.704164791743, 5920.79277225295, 5941.881162376728, 5738.799142030009, 5826.883462330729, 5843.824685259449, 5855.151003104526, 5883.646981181143, 6536.41172294491, 6549.181352330958, 6563.704633291513, 6573.506986975948, 6618.669066546653, 6893.965530172317, 7002.334778196063, 7054.823629409255, 7121.643917804089, 7199.640017999079, 6135.07955838608, 7234.855302893965, 7249.782506524813, 7280.74517391894, 7304.233055529146, 5977.342492325841, 6450.967845144848, 6641.601503909781, 6697.363750443748, 6729.091572637729, 6213.782517611862, 7442.851142977219, 7481.0, 7481.9251807482315, 7497.962510187414, 6763.898541521881, 6838.589684618939, 6866.896996545056, 6963.059986901703, 7043.471739619446, 7198.640067996579, 7253.963730181315, 7254.782356529313, 7286.927130728731, 7291.562506249642, 7148.142222933281, 7156.713731450788, 7160.498765086394, 7167.89248161278, 7178.28217178278, 7152.606606636581, 7161.67772450241, 7164.534305270141, 7185.461090418239, 7191.604461754549, 5551.472610102034, 5609.49514543693, 5720.342160651495, 5727.427257274239, 5746.511546518509, 4974.427940786856, 4989.027139707722, 5036.84889453317, 5054.115529782273, 5057.342545469065, 5594.104943209149, 6584.9012264816065, 6586.670666466657, 6651.63416012124, 6765.086713293741, 7212.3148300911325, 7244.637768111679, 7283.271672832674, 7284.599347035858, 7303.671334789948, 6758.324167583301, 6765.0, 6783.62690052042, 6783.830404239933, 6786.728530858711, 6166.075088736636, 6295.842603934938, 6346.58747181441, 6351.777687780906, 6379.617222966637, 6817.659117044227, 7433.962830185815, 7496.962515187389, 7504.0, 7509.474336796372, 5791.797287094932, 7000.719971201095, 7057.082579264663, 7109.680064397011, 7289.671964761705, 7128.786136415916, 7465.141508726566, 7534.171241163358, 7552.244775522624, 7559.886601700869, 7178.856422871618, 7180.138383394026, 7181.389581885389, 7196.0, 7198.604076775828, 7325.6337183141695, 7330.486865919334, 7353.632318384166, 7364.5581265124265, 7369.631518424165, 6824.7952561423235, 6889.207741109636, 6913.412359949461, 6953.79138625842, 7082.645867706491, 6943.79168624942, 6959.860802784017, 7015.649217539205, 7016.087908571853, 7041.929580704127, 6121.418465245794, 6629.839778038728, 6887.965560172167, 6897.655117244018, 6913.75801846933, 7027.297270272932, 7029.26192749754, 7035.894461582978, 7043.506954513135, 7065.6113913735235, 6626.171728533934, 6697.12937318165, 6721.966390168213, 6787.287334829772, 6826.761063362957], "value": 6513.533574785867, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.9845003099938, 808.8139727862591, 856.7258477287263, 875.2341701011619, 886.5788750343586, 38281.61718382815, 39171.41242881359, 39229.2154156917, 39242.018949526275, 39308.21383572327, 32805.835970820175, 38590.84227473173, 38956.61043389565, 39262.8036859816, 39317.0, 6673.833154171136, 7821.37429005681, 7895.921040789604, 7964.362850971937, 7990.720324788634, 7844.372450203999, 7941.523508589475, 8273.33813294938, 8494.490330580154, 8511.0, 7864.4101692373015, 8087.867698522212, 8502.319814414832, 8562.272206862424, 8573.95713021434, 8451.197136272045, 8480.74557763265, 8496.362772792065, 8538.0, 8540.74377768668, 8383.83232335356, 8405.663773449054, 8416.915830841735, 8451.45065570742, 8471.15288471154, 36458.90623281306, 36596.81701591502, 36640.0, 36669.89990300295, 36739.0, 3401.489776533515, 3512.0868574170645, 3674.375356189454, 3679.0618392310057, 3680.1719613087107, 30230.0, 30297.69702302971, 30306.696933030827, 30329.393412131856, 30416.54375184374, 3180.681931806801, 3232.660570640096, 3241.2383089973837, 3251.6910893465083, 3258.3483303339426, 262732.0, 265594.9999999981, 265865.0, 313491.0, 314883.42558287294, 39894.401583976265, 43150.13699725988, 43825.0, 48064.5193548067, 48096.0, 50369.24446133311, 50672.0, 50743.98512029739, 50781.23828142581, 50818.47544573669, 15776.211189440482, 15876.285567149505, 15877.285522151531, 15887.761683574756, 15907.125108119164, 12918.289494077915, 12949.223046617233, 13027.609171724864, 13029.022823288291, 13058.934705326412, 13562.186268823902, 13587.0, 13601.455941762417, 13610.93194534021, 13626.04617676773, 5263.842084737464, 6022.156898034279, 6050.485708714808, 6056.545759068087, 6110.26676798787, 942.1755963531909, 952.6522819171091, 977.3891317926297, 1014.8832884218261, 1015.8222311095528, 23044.65433018506, 23479.41301467443, 23490.64764028541, 23931.9657829083, 24002.159924402564, 1012.8959434216592, 1047.5076713944586, 1129.6836885672026, 1177.8174382970483, 1180.5218886351042, 12056.819147712791, 12376.319302438453, 12407.25556466615, 12477.875221247852, 12571.0, 345733.0, 346997.0, 347059.0, 347223.0, 348387.2580637081, 53839.0, 53840.0, 53846.7307663467, 54154.916901661745, 54216.45783542193, 1490.4336352186099, 1567.1145802621254, 1571.9371225150867, 1572.9921350393174, 1584.5246426072133, 22364.88817555902, 22651.0, 23114.53770924606, 23127.421814454774, 24085.0, 29955.0, 36633.63366366302, 36918.44622330721, 36925.815370922974, 36926.446103307935, 302491.0, 303593.0, 305161.0, 305356.0, 305571.0, 32156.356872862878, 32355.51466728048, 32506.34987300288, 33567.0, 33717.49423758694, 43318.917027074574, 44499.77750111229, 44571.55428445674, 44619.43831965866, 44781.77609111933, 28066.017689380773, 28404.715952841034, 29604.2025268727, 29659.406811864075, 30837.691623083483, 29678.703212967594, 30995.535066973564, 31278.687213127578, 31331.37337253288, 31466.0, 35554.8222258887, 36011.45982810207, 37720.0, 37746.62253377541, 37781.05547361338, 27717.0, 28820.84716611312, 29864.552031719108, 31852.840735796173, 31882.0, 23018.884905575364, 24314.63528047116, 24632.753672463044, 24770.380740481633, 24819.751802482468], "value": 17469.900184024344, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.9819756308528, 533.7464704265475, 534.644461433147, 538.8410418926417, 543.1364131031659, 23289.534209315803, 24061.27816165518, 24130.27609171723, 24131.15540956067, 24139.51720965582, 24955.500889982188, 27442.5883611746, 27592.17223483294, 27677.861610691918, 27792.16623501293, 6923.653817309152, 7066.752663656747, 7103.25415831338, 7109.715611375539, 7112.4665650076195, 7057.329553692391, 7083.574985500861, 7083.85832283354, 7099.85800283994, 7109.111361079865, 6034.396560343975, 6403.903941440859, 6676.232233293161, 6706.966465167668, 6738.52830301879, 6599.703013364386, 6611.537192396534, 6645.800625981228, 6662.933370666304, 6665.766698165589, 6117.877642447126, 6129.632222066645, 6147.170132032208, 6164.630122192682, 6168.259808822925, 30865.0, 32123.35753284921, 32488.837555812304, 32531.837340813145, 32908.01275961701, 2310.3415526575013, 2363.3382652857226, 2379.6906402167606, 2380.4405964598304, 2384.141708984764, 20164.49588760278, 20705.68941465879, 20719.27482538104, 20725.378238652866, 20750.169993200252, 1989.293800700745, 2291.6562515622622, 2331.790138887491, 2341.590221711194, 2354.176038386568, 216534.0, 218297.0, 221838.0, 222356.77643223683, 225538.87230563906, 13574.59276221715, 14302.713945721027, 15921.442749503713, 16285.430009949594, 16972.575685607957, 21980.791056492046, 22198.55602887933, 22239.55520889573, 22268.99789509476, 22311.661300322034, 10976.506057227443, 11003.449827508754, 11113.777724445466, 11171.83242251367, 11234.0, 10331.638392656372, 10337.896621033844, 10379.688609341732, 10382.117520010883, 10392.116670083127, 9204.723858284262, 9706.272029597809, 9724.0, 9881.703548893545, 9904.504774761233, 2815.211740712604, 2822.520171570838, 2834.5464725643797, 2840.7331568696163, 2960.57071724603, 941.3598752848129, 952.7237101240701, 960.5053397500293, 1010.9140723038624, 1013.3717095400899, 17621.823781762276, 22403.55192896133, 22552.436189095402, 23146.421339466313, 23370.948307326213, 907.2424525521131, 996.5963784667222, 1007.6372505897871, 1055.9736006599896, 1056.2870062707796, 11142.665720028412, 11559.595414160633, 11571.132165087653, 14877.553673389815, 15963.44127955516, 607520.0, 629506.8524657347, 637375.0, 645371.0, 650398.0, 176400.0, 179208.10395947937, 179351.10324448295, 180458.09770951324, 184430.0778496099, 1154.1689983212075, 1224.1492162946668, 1306.4251729239104, 1318.973620527584, 1320.8216890719823, 24501.754982449947, 24983.50032999366, 25691.0, 25894.87052564725, 25979.350516237246, 74099.25900740923, 76664.0, 78911.0, 79412.60293698494, 79513.60243198978, 102419.48790256299, 102647.46028809724, 106824.46587767011, 106999.0, 108169.45915270373, 12032.09759268041, 12258.080643951918, 12442.688932776753, 12446.937765311115, 12463.065270104596, 19813.405597832087, 19943.0, 19957.301494447624, 20044.799552004293, 20084.096215670037, 16003.439879604159, 16005.839941600902, 16161.27274272684, 16183.838161618232, 16246.431374901822, 13756.793648095487, 13927.860721392657, 14083.436662533384, 14735.705285894437, 14852.886033547311, 16072.437464689148, 16276.27860157228, 16701.49895503137, 17038.0, 17059.91470042642, 12484.687882803002, 12603.0, 12641.3679316032, 12657.87342126567, 12662.050346224254, 16069.678606427573, 16212.918935405247, 16343.83656163423, 16483.175841208133, 16549.83450165483], "value": 12756.224078637188, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1230.2495477758569, 1348.5684580934098, 1478.7338279109756, 1492.3433689176761, 1496.8278647955485, 13997.0, 14606.853931460682, 14627.707445851076, 14632.34154463049, 14657.486987955424, 14341.713165736679, 14607.707845843104, 14676.486322978697, 14732.779008314883, 14767.92616036921, 2971.2423332050353, 2990.8953186638473, 3031.863566139518, 3031.924201894948, 3035.362573859481, 2934.970650293491, 2955.1577800326913, 2964.347843474432, 2968.584398184256, 2975.3751712140474, 2826.915192544227, 2943.499605067144, 2969.6584892737396, 2985.999690103818, 3048.725614694677, 3023.697630236981, 3026.1980575147604, 3051.313454472748, 3061.0510741670155, 3067.4631939410624, 2958.304798372381, 2969.465496210681, 2979.031814660244, 2979.582858399804, 2999.5950546676354, 14232.0, 14243.572692819233, 14354.851611871025, 14365.497207597788, 14377.0, 6571.507136964699, 7787.18234585366, 8152.836943261102, 8230.382721295926, 8348.914641096679, 4659.114768194032, 5019.322391477177, 5128.102582048164, 5173.094708426048, 5191.8182863599595, 1482.948096816617, 1485.9182744949023, 1493.6340596553914, 1510.6903084867686, 1517.3854588891516, 21400.78599214019, 21559.892200539052, 21561.167300779452, 21571.0, 21611.567768644538, 6868.179932317939, 6929.3070692930305, 6953.026576279325, 7076.186238582526, 7109.82225444368, 14912.62718432048, 15015.699686006219, 15027.323770430137, 15032.0, 15036.022658527161, 7808.297253247121, 7909.1299957004685, 7954.164812694699, 7966.243206895336, 7968.0836703779805, 7236.493445458873, 7274.236205198484, 7310.488265821447, 7371.741989030358, 7387.0, 5184.611154163453, 5359.946400536022, 5428.809991650273, 5437.13005919051, 5440.755166017539, 1626.8454496822783, 1629.380835282585, 1630.070859610011, 1631.4126914310839, 1660.709375859244, 802.1216767639426, 808.9676412943417, 824.1675907333636, 830.3357314148657, 849.9630450849953, 6718.002788340929, 6886.48351373649, 6931.064306318583, 8819.206271435602, 8861.689840855539, 1005.0, 1013.2096964367805, 1034.798214348195, 1050.6480329089688, 1066.1630619963291, 4310.418093557392, 4787.664863459594, 5129.153689641167, 5148.819791307361, 5296.92054619181, 18747.437576872715, 19091.04544772756, 19250.614987700446, 19393.224271029, 19449.22203111888, 16435.0, 18535.53661158455, 19532.31636892702, 19582.90208548948, 19656.410307690792, 10137.949310253402, 10167.4916254187, 10212.336198147097, 10231.232657550863, 10300.896991030144, 6343.682815859096, 7998.3201427879285, 8398.370122240978, 8429.915700842914, 8525.701600444203, 17424.867383620025, 17827.19777609985, 18076.638467230845, 18191.545211369823, 18241.270349186416, 14443.013052672599, 17871.642567148323, 18464.63070738551, 18629.81370186335, 18669.43991680252, 10457.633982810565, 11319.773604528027, 11406.429678515875, 11511.079113671054, 11579.652610421701, 12927.612171634866, 13106.737865242832, 13108.868911310765, 13117.409716562586, 13130.2778347188, 9254.4910029949, 10424.530896109845, 10504.684859454228, 10562.735931601772, 10620.256582039183, 9437.811243775224, 9526.714198574055, 10069.647562335284, 10561.0, 10760.838587421038, 9544.42733435996, 9618.951905240428, 9769.85345219808, 9772.755681108029, 9782.0, 6224.533160012927, 6753.797386078426, 6816.796105830252, 7045.2954704531185, 7193.892091618734, 6960.825979350557, 7117.928820711727, 7304.452166087671, 7413.258674132544, 7420.5547667140145], "value": 6462.984439769969, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.9770504889245, 479.49652864492276, 486.1468123443356, 490.70312460961094, 500.9949900500994, 5378.946210537893, 5459.508644222019, 6573.86852262955, 6595.868082638356, 6660.367265109813, 6238.4385405313515, 6345.52408569358, 6456.644884531361, 6469.70886310116, 6481.31946145655, 4332.65338772899, 4345.587169218919, 4615.792289346988, 4901.240307752304, 4905.484924082964, 4885.633577481685, 4892.315075889361, 4894.804207831682, 4932.975335123338, 4933.284673722308, 4638.026014536935, 4858.757062146896, 4869.9269510957365, 4875.63432742544, 4894.486078961711, 4681.8829529261875, 4867.8783030424165, 4888.902221955559, 4896.46138924718, 4920.532549407801, 3774.245150969817, 4277.700560960734, 4317.805698743564, 4320.503142138631, 4325.567443255681, 5082.059818933472, 6140.9385906140815, 6229.193533875216, 6287.937120628781, 6337.746490140435, 5570.916436253459, 5751.568632352591, 5799.565032622528, 5817.680027598481, 5832.6792026438525, 6985.755498557577, 7002.739506888758, 7037.22590515043, 7064.540804847668, 7067.399271061966, 4317.546657600969, 4440.600345968878, 4684.414448193976, 4942.555170034678, 4963.205887058053, 6176.722047507873, 6380.521460890405, 7165.456263026603, 7479.850402991964, 7507.737229196952, 4190.182914331737, 4865.861519320089, 5032.616030591616, 5049.267856160836, 5061.670991385548, 4592.816287348535, 4983.526564976321, 5171.069207542679, 5178.689278643294, 5179.870503237374, 5092.719900405513, 5762.789814138994, 5784.94215057844, 5802.56292357211, 5839.299284085937, 7067.363937245673, 7070.7525236616475, 7077.893831592531, 7085.681144348517, 7093.32613401726, 1353.9574527613681, 1390.9443622254996, 1463.9853601464063, 1744.842964133234, 1766.655502177089, 1173.7183076061683, 1175.8177482490057, 1180.1090176916546, 1187.7446349035, 1204.163106640876, 5237.423883372826, 5412.242286079952, 6213.720382582705, 6571.67141642916, 6681.0, 6800.897986530206, 7172.964135179291, 7195.424366050808, 7204.046341000165, 7276.527025743309, 5688.829335119953, 5698.0, 6583.012548117821, 6615.569987950767, 6678.6326752028135, 3129.890453834105, 3268.836558172082, 3731.738778285548, 3749.2126653402547, 3785.734998550075, 6542.967285163544, 7350.038723861372, 7444.739434119888, 7497.66260518278, 7526.5860377678655, 6041.72812223451, 6379.936200638027, 7241.710331586783, 7471.364933980563, 7482.176960534337, 7433.182349941502, 7461.515001524884, 7463.328300452985, 7478.887816682754, 7503.924960750432, 5971.984762590283, 6387.712552935222, 6858.657067146523, 6911.239763626098, 7231.602261875647, 5912.586118971631, 6192.783252586138, 7051.1186101737285, 7096.396806271318, 7100.147982242164, 6132.78535251264, 6927.856903611049, 7472.589007604416, 7495.201151723656, 7501.2123727007875, 4446.355278484537, 5049.752859977421, 5055.848324550269, 5059.367579052619, 5064.898702026013, 7193.964030179815, 7278.381337586365, 7280.817979550554, 7285.927140728525, 7299.452541059336, 6727.428168605527, 6737.273317679933, 6896.896546551906, 6897.930820722796, 7057.894131588133, 5476.644018138808, 5901.173835663097, 6896.827499337693, 6988.091548098715, 7044.084269044992, 6266.68666566679, 6873.862522749617, 6874.381305682513, 6898.172219333712, 6910.896336554855, 6119.724612392364, 7011.1586609607175, 7015.579065256101, 7044.084269044992, 7044.647767611496, 5436.782528698808, 5902.881942361215, 6181.443670069715, 6876.071730316343, 6884.65576721172], "value": 5106.3998472098265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.1883987034199, 825.0923983618021, 845.1379592815329, 851.6678495386797, 890.0565400675283, 17849.0, 17895.821041789593, 18023.09884505776, 18237.452876413696, 18368.35710750124, 13763.449462021532, 15471.767923481157, 15499.69000619987, 17950.910245448755, 19817.504562385948, 1040.2926010313004, 1050.406520316019, 1050.4432650695144, 1051.1590727418072, 1057.376148072637, 1061.9946900265488, 1066.4667666166918, 1067.9199060070487, 1069.6256310291374, 1072.7639919217759, 1118.4351902289332, 1134.7787181499612, 1135.2280449294476, 1136.5681041204332, 1137.7383201863577, 1055.0241027049985, 1098.3080659184743, 1099.5546803544537, 1104.4588151805558, 1113.8162203236457, 799.9800004999921, 803.3894240377297, 805.1103530598663, 807.2290962131209, 807.8061265296345, 18315.633687326314, 18426.262949482003, 18669.906650466663, 18762.343317983807, 18793.77840440367, 18202.726959095624, 18608.06959652012, 18768.0, 18907.527311817175, 18913.24347026117, 15693.921530392276, 15770.448034318859, 15846.683066338725, 15868.761968570365, 15870.12714300713, 3825.598312177209, 3841.3469710149343, 3845.9615403845883, 3855.4602355670227, 3863.1114843586133, 143297.2835135828, 151387.90518972694, 155921.0, 158556.0, 164584.0, 14103.224322662352, 14423.206723630301, 14910.403583856742, 15132.167730774912, 15243.237838108273, 28629.0, 28659.996900108395, 28871.711282886903, 29035.274118147216, 29198.27004324909, 8589.527575983257, 8638.222559969507, 8640.567971601395, 8644.697435589724, 8648.524331161847, 4031.0, 4056.411820286101, 4102.897427564335, 4103.117829666636, 4118.032262418329, 4846.200376937836, 4936.234883593049, 4939.481354457802, 4941.703497790072, 4952.4057113146655, 1021.1779517488493, 1082.220801023262, 1084.7613525024483, 1091.3888222595372, 1093.6445655161945, 659.5844617890698, 680.9727610895509, 686.8935315026179, 711.0933559711409, 714.2821464428216, 11590.014848737952, 11634.243774154653, 12652.746945061048, 13058.804117938238, 13097.934510327577, 610.2920612089954, 655.7672026430644, 673.3569441183657, 683.1835956032593, 707.2538471912183, 5297.549708274762, 5744.138379243147, 5757.712114394348, 5801.158831969426, 5943.286805583358, 140780.0, 143899.0, 147088.5291147096, 147847.52152478337, 150330.0, 106751.0, 121289.0, 124234.0, 131046.0, 132845.0, 80282.5985870067, 91449.0, 109288.0, 109619.45190273998, 112516.87483125228, 11605.89743974321, 11683.707907302045, 11886.108541859223, 12537.749245015231, 12750.107492475438, 43572.25710971653, 44848.32727509025, 44957.0, 45007.54992450034, 49041.0, 62148.0, 77529.22470775446, 78517.0, 79512.01219969547, 82834.5858270729, 18296.72554911704, 18329.725054124465, 18381.264749409875, 18582.58426404559, 18676.906615466836, 18457.446276611725, 19134.138963746387, 19421.902890485457, 19912.0, 20058.59882802365, 17886.46340609784, 18343.26626934908, 18681.719774203128, 18721.063946802333, 18942.810571894104, 14766.704665906836, 17902.28390864403, 18266.543336416165, 18311.633767324845, 18422.539436514195, 17480.47558573245, 17591.82408175953, 17792.288308468032, 18078.09609519545, 18556.44330670082, 12051.337176455028, 12422.503099876265, 12524.624261272176, 12736.74526509483, 12998.870011299767, 12588.811167832673, 13474.19154850712, 14750.926245368704, 14798.704025919205, 14828.258587070388], "value": 9272.151547416617, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 116.0, 107.0, 131.0, 161.0, 106.0, 142.0, 93.0, 154.0, 168.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 129.0, 145.0, 109.0, 151.0, 127.0, 95.0, 161.0, 132.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 264.0, 205.0, 195.0, 198.0, 200.0, 194.0, 198.0, 203.0, 199.0], "value": 205.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 86.0, 72.0, 160.0, 60.0, 66.0, 83.0, 90.0, 137.0, 56.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 120.0, 72.0, 70.0, 74.0, 105.0, 68.0, 69.0, 107.0], "value": 83.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 91.0, 96.0, 113.0, 90.0, 104.0, 102.0, 92.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 102.0, 97.0, 98.0, 98.0, 96.0, 112.0, 147.0, 95.0, 97.0], "value": 103.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 111.0, 90.0, 92.0, 91.0, 98.0, 77.0, 130.0, 144.0, 115.0], "value": 103.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 48.0, 50.0, 51.0, 51.0, 49.0, 51.0, 51.0, 49.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 63.0, 73.0, 54.0, 51.0, 63.0, 74.0, 75.0, 78.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 102.0, 97.0, 101.0, 76.0, 79.0, 80.0, 79.0, 78.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 138.0, 139.0, 126.0, 143.0, 144.0, 138.0, 154.0, 149.0, 162.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 171.0, 173.0, 207.0, 175.0, 177.0, 175.0, 171.0, 172.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 64.0, 65.0, 66.0, 63.0, 62.0, 64.0, 61.0], "value": 64.1, "unit": "score"}]}]} 03:10:27 INFO - Return code: 0 03:10:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:10:27 INFO - # TBPL SUCCESS # 03:10:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:10:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:10:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:10:27 INFO - 2017-06-13 03:10:27.212 screenresolution[1674:10949] starting screenresolution argv=screenresolution get 03:10:27 INFO - 2017-06-13 03:10:27.214 screenresolution[1674:10949] Display 0: 1600x1200x32@60 03:10:27 INFO - 2017-06-13 03:10:27.220 screenresolution[1675:10952] starting screenresolution argv=screenresolution list 03:10:27 INFO - Available Modes on Display 0 03:10:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:10:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:10:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:10:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:10:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:10:27 INFO - Intel Iris: 03:10:27 INFO - Chipset Model: Intel Iris 03:10:27 INFO - Type: GPU 03:10:27 INFO - Bus: Built-In 03:10:27 INFO - VRAM (Dynamic, Max): 1536 MB 03:10:27 INFO - Vendor: Intel (0x8086) 03:10:27 INFO - Device ID: 0x0a2e 03:10:27 INFO - Revision ID: 0x0009 03:10:27 INFO - Displays: 03:10:27 INFO - Display: 03:10:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:10:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:10:27 INFO - Display Serial Number: 1600x1200 60 03:10:27 INFO - Main Display: Yes 03:10:27 INFO - Mirror: Off 03:10:27 INFO - Online: Yes 03:10:27 INFO - Rotation: Supported 03:10:27 INFO - Return code: 0 03:10:27 INFO - Running post-action listener: _resource_record_post_action 03:10:27 INFO - [mozharness: 2017-06-13 10:10:27.453706Z] Finished run-tests step (success) 03:10:27 INFO - Running post-run listener: _resource_record_post_run 03:10:27 INFO - Total resource usage - Wall time: 1247s; CPU: 53.0%; Read bytes: 40622592; Write bytes: 473589760; Read time: 268; Write time: 1528 03:10:27 INFO - TinderboxPrint: CPU usage
53.1% 03:10:27 INFO - TinderboxPrint: I/O read bytes / time
40,622,592 / 268 03:10:27 INFO - TinderboxPrint: I/O write bytes / time
473,589,760 / 1,528 03:10:27 INFO - TinderboxPrint: CPU idle
2,342.3 (46.9%) 03:10:27 INFO - TinderboxPrint: CPU system
1,005.6 (20.2%) 03:10:27 INFO - TinderboxPrint: CPU user
1,641.7 (32.9%) 03:10:27 INFO - TinderboxPrint: Swap in / out
194,740,224 / 0 03:10:27 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 150991360; Write bytes: 147593216; Read time: 11890; Write time: 318 03:10:27 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:10:27 INFO - run-tests - Wall time: 1232s; CPU: 53.0%; Read bytes: 38480384; Write bytes: 313704448; Read time: 247; Write time: 1170 03:10:27 INFO - Running post-run listener: _upload_blobber_files 03:10:27 INFO - Blob upload gear active. 03:10:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:10:27 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:10:27 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:10:27 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:10:28 INFO - (blobuploader) - INFO - Open directory for files ... 03:10:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:10:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:10:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:29 INFO - (blobuploader) - INFO - Done attempting. 03:10:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:10:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:10:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:30 INFO - (blobuploader) - INFO - Done attempting. 03:10:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:10:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:10:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:31 INFO - (blobuploader) - INFO - Done attempting. 03:10:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:10:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:10:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:33 INFO - (blobuploader) - INFO - Done attempting. 03:10:33 INFO - (blobuploader) - INFO - Iteration through files over. 03:10:33 INFO - Return code: 0 03:10:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:10:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:10:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f854007a0a21248d5d99cd723f804013bdb8981e9a29aa6fc7afba5a16aba59f0328a10e2e8db06649c3226f16e425292ffa72f02e5b7eac73a267e263d514b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/929804e1f6537221545a76ec5062ca28f3110660b58eabacca9d2467d35cdf8bfdddeaa361750d68934391986c8aac8ae7e61d03b4ce2506367d3a30799890a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2caf70e4a88354fb2492454ca9e76394382426d725b485582fa71302a091763bdb0b41332c11a01ced96e8d333853a1fa70c2b88775eec53e3fb2bde5d73b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1936fe5e6cf7eeb94215fae6bf28a61e7642a144d3e6ddfc8f06fab6df1230857525297a24264b8de517b89d02ae0b73d21f10b013081918b3c210842827f65"} 03:10:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:10:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:10:33 INFO - Contents: 03:10:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f854007a0a21248d5d99cd723f804013bdb8981e9a29aa6fc7afba5a16aba59f0328a10e2e8db06649c3226f16e425292ffa72f02e5b7eac73a267e263d514b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/929804e1f6537221545a76ec5062ca28f3110660b58eabacca9d2467d35cdf8bfdddeaa361750d68934391986c8aac8ae7e61d03b4ce2506367d3a30799890a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2caf70e4a88354fb2492454ca9e76394382426d725b485582fa71302a091763bdb0b41332c11a01ced96e8d333853a1fa70c2b88775eec53e3fb2bde5d73b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1936fe5e6cf7eeb94215fae6bf28a61e7642a144d3e6ddfc8f06fab6df1230857525297a24264b8de517b89d02ae0b73d21f10b013081918b3c210842827f65"} 03:10:33 INFO - Running post-run listener: copy_logs_to_upload_dir 03:10:33 INFO - Copying logs to upload dir... 03:10:33 INFO - mkdir: /builds/slave/test/build/upload/logs 03:10:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1328.295352 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-13 03:10:33.376484) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 03:10:33.380537) ========= 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.YU3xgden3H/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.dKdnEtbaNu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f854007a0a21248d5d99cd723f804013bdb8981e9a29aa6fc7afba5a16aba59f0328a10e2e8db06649c3226f16e425292ffa72f02e5b7eac73a267e263d514b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/929804e1f6537221545a76ec5062ca28f3110660b58eabacca9d2467d35cdf8bfdddeaa361750d68934391986c8aac8ae7e61d03b4ce2506367d3a30799890a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2caf70e4a88354fb2492454ca9e76394382426d725b485582fa71302a091763bdb0b41332c11a01ced96e8d333853a1fa70c2b88775eec53e3fb2bde5d73b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1936fe5e6cf7eeb94215fae6bf28a61e7642a144d3e6ddfc8f06fab6df1230857525297a24264b8de517b89d02ae0b73d21f10b013081918b3c210842827f65"} build_url:https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010903 build_url: 'https://queue.taskcluster.net/v1/task/Adz_gDxNSDOtawVjWf_fZA/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f854007a0a21248d5d99cd723f804013bdb8981e9a29aa6fc7afba5a16aba59f0328a10e2e8db06649c3226f16e425292ffa72f02e5b7eac73a267e263d514b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/929804e1f6537221545a76ec5062ca28f3110660b58eabacca9d2467d35cdf8bfdddeaa361750d68934391986c8aac8ae7e61d03b4ce2506367d3a30799890a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2caf70e4a88354fb2492454ca9e76394382426d725b485582fa71302a091763bdb0b41332c11a01ced96e8d333853a1fa70c2b88775eec53e3fb2bde5d73b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1936fe5e6cf7eeb94215fae6bf28a61e7642a144d3e6ddfc8f06fab6df1230857525297a24264b8de517b89d02ae0b73d21f10b013081918b3c210842827f65"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 03:10:33.496713) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 03:10:33.497092) ========= 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.YU3xgden3H/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.dKdnEtbaNu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.005261 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 03:10:33.526876) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 03:10:33.527592) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 03:10:33.528543) ========= ========= Total master_lag: 0.25 =========