builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0018 starttime: 1497627781.85 results: success (0) buildid: 20170616074221 builduid: 0cc8c9312cef456a8bff95b40e538358 revision: 2b0d662801c24270a80187c9444dd829ee06be4e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:01.850576) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:01.851784) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:01.852410) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:01.929441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:01.929797) ========= 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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-16 08:43:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.5M=0.001s 2017-06-16 08:43:02 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.615279 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:02.568832) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:02.569199) ========= 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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.029245 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:02.624042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-16 08:43:02.624463) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2b0d662801c24270a80187c9444dd829ee06be4e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2b0d662801c24270a80187c9444dd829ee06be4e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SvPJfzVrrv/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.6eR6C2gOhm/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-16 08:43:02,726 truncating revision to first 12 chars 2017-06-16 08:43:02,726 Setting DEBUG logging. 2017-06-16 08:43:02,726 attempt 1/10 2017-06-16 08:43:02,726 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2b0d662801c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-16 08:43:04,016 unpacking tar archive at: mozilla-beta-2b0d662801c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.565968 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-16 08:43:04.212784) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:04.213141) ========= script_repo_revision: 2b0d662801c24270a80187c9444dd829ee06be4e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:04.213651) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:04.213985) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-16 08:43:04.229401) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-16 08:43:04.229708) ========= /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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:43:04 INFO - MultiFileLogger online at 20170616 08:43:04 in /builds/slave/test 08:43:04 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 08:43:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:04 INFO - {'append_to_log': False, 08:43:04 INFO - 'base_work_dir': '/builds/slave/test', 08:43:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:43:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:04 INFO - 'branch': 'Mozilla-Beta', 08:43:04 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:04 INFO - 'config_files': ('talos/mac_config.py',), 08:43:04 INFO - 'default_actions': ('clobber', 08:43:04 INFO - 'read-buildbot-config', 08:43:04 INFO - 'download-and-extract', 08:43:04 INFO - 'populate-webroot', 08:43:04 INFO - 'create-virtualenv', 08:43:04 INFO - 'install', 08:43:04 INFO - 'setup-mitmproxy', 08:43:04 INFO - 'run-tests'), 08:43:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:04 INFO - 'download_minidump_stackwalk': True, 08:43:04 INFO - 'download_symbols': 'ondemand', 08:43:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:04 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:04 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:04 INFO - 'gecko_profile': False, 08:43:04 INFO - 'gecko_profile_interval': 0, 08:43:04 INFO - 'installer_path': 'installer.exe', 08:43:04 INFO - 'log_level': 'info', 08:43:04 INFO - 'log_name': 'talos', 08:43:04 INFO - 'log_to_console': True, 08:43:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:04 INFO - 'opt_config_files': (), 08:43:04 INFO - 'pip_index': False, 08:43:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:04 INFO - 'cmd': ('bash', 08:43:04 INFO - '-c', 08:43:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:04 INFO - 'enabled': True, 08:43:04 INFO - 'halt_on_failure': False, 08:43:04 INFO - 'name': 'check_screen_resolution'},), 08:43:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:04 INFO - 'cmd': ('bash', 08:43:04 INFO - '-c', 08:43:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:04 INFO - 'enabled': True, 08:43:04 INFO - 'halt_on_failure': False, 08:43:04 INFO - 'name': 'check_screen_resolution'},), 08:43:04 INFO - 'run_cmd_checks_enabled': True, 08:43:04 INFO - 'suite': 'dromaeojs-e10s', 08:43:04 INFO - 'system_bits': '32', 08:43:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:04 INFO - 'title': 't-yosemite-r7-0018', 08:43:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:04 INFO - 'use_talos_json': True, 08:43:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:04 INFO - 'work_dir': 'build'} 08:43:04 INFO - [mozharness: 2017-06-16 15:43:04.439209Z] Running clobber step. 08:43:04 INFO - Running pre-action listener: _resource_record_pre_action 08:43:04 INFO - Running main action method: clobber 08:43:04 INFO - rmtree: /builds/slave/test/build 08:43:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:07 INFO - Running post-action listener: _resource_record_post_action 08:43:07 INFO - [mozharness: 2017-06-16 15:43:07.005444Z] Finished clobber step (success) 08:43:07 INFO - [mozharness: 2017-06-16 15:43:07.005720Z] Running read-buildbot-config step. 08:43:07 INFO - Running pre-action listener: _resource_record_pre_action 08:43:07 INFO - Running main action method: read_buildbot_config 08:43:07 INFO - Using buildbot properties: 08:43:07 INFO - { 08:43:07 INFO - "project": "", 08:43:07 INFO - "product": "firefox", 08:43:07 INFO - "script_repo_revision": "production", 08:43:07 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 08:43:07 INFO - "repository": "", 08:43:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 08:43:07 INFO - "buildid": "20170616074221", 08:43:07 INFO - "pgo_build": "False", 08:43:07 INFO - "basedir": "/builds/slave/test", 08:43:07 INFO - "buildnumber": 7, 08:43:07 INFO - "slavename": "t-yosemite-r7-0018", 08:43:07 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:43:07 INFO - "platform": "yosemite_r7_devedition", 08:43:07 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 08:43:07 INFO - "branch": "mozilla-beta", 08:43:07 INFO - "repo_path": "releases/mozilla-beta", 08:43:07 INFO - "revision": "2b0d662801c24270a80187c9444dd829ee06be4e", 08:43:07 INFO - "stage_platform": "macosx64-devedition", 08:43:07 INFO - "builduid": "0cc8c9312cef456a8bff95b40e538358", 08:43:07 INFO - "slavebuilddir": "test" 08:43:07 INFO - } 08:43:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 08:43:07 INFO - Running post-action listener: _resource_record_post_action 08:43:07 INFO - [mozharness: 2017-06-16 15:43:07.011470Z] Finished read-buildbot-config step (success) 08:43:07 INFO - [mozharness: 2017-06-16 15:43:07.011716Z] Running download-and-extract step. 08:43:07 INFO - Running pre-action listener: _resource_record_pre_action 08:43:07 INFO - Running main action method: download_and_extract 08:43:07 INFO - mkdir: /builds/slave/test/build/tests 08:43:07 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')]} 08:43:07 INFO - trying https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 08:43:07 INFO - Downloading https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/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 08:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/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 08:43:07 INFO - Downloaded 1290 bytes. 08:43:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 08:43:07 INFO - Using the following test package requirements: 08:43:07 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 08:43:07 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:43:07 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 08:43:07 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 08:43:07 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:43:07 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 08:43:07 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:43:07 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 08:43:07 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 08:43:07 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 08:43:07 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:43:07 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 08:43:07 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 08:43:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:43:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:43:07 INFO - Fetch https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:43:10 INFO - Content-Length response header: 59544989 08:43:10 INFO - Bytes received: 59544989 08:43:28 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 08:43:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:43:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:43:28 INFO - Fetch https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:43:32 INFO - Content-Length response header: 59544989 08:43:32 INFO - Bytes received: 59544989 08:43:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 08:43:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 08:43:49 INFO - Fetch https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 08:43:50 INFO - Content-Length response header: 14454470 08:43:50 INFO - Bytes received: 14454470 08:43:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:50 INFO - trying https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:43:50 INFO - Downloading https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 08:43:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/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 08:43:52 INFO - Downloaded 66867109 bytes. 08:43:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:43:52 INFO - mkdir: /builds/slave/test/properties 08:43:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:43:52 INFO - Writing to file /builds/slave/test/properties/build_url 08:43:52 INFO - Contents: 08:43:52 INFO - build_url:https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:43:52 INFO - Running post-action listener: _resource_record_post_action 08:43:52 INFO - Running post-action listener: set_extra_try_arguments 08:43:52 INFO - [mozharness: 2017-06-16 15:43:52.909851Z] Finished download-and-extract step (success) 08:43:52 INFO - [mozharness: 2017-06-16 15:43:52.909968Z] Running populate-webroot step. 08:43:52 INFO - Running pre-action listener: _resource_record_pre_action 08:43:52 INFO - Running main action method: populate_webroot 08:43:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:43:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:43:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:43:52 INFO - '--tppagecycles', 08:43:52 INFO - '7'], 08:43:52 INFO - 'tests': ['tsvgm']}}, 08:43:52 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:43:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:43:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:43:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['damp', 'tps']}, 08:43:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'tests': ['damp', 'tps']}, 08:43:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['dromaeo_dom']}, 08:43:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:43:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:43:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:43:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:43:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['a11yr', 08:43:52 INFO - 'ts_paint', 08:43:52 INFO - 'tpaint', 08:43:52 INFO - 'sessionrestore', 08:43:52 INFO - 'sessionrestore_no_auto_restore', 08:43:52 INFO - 'tabpaint']}, 08:43:52 INFO - 'other-e10s': {'tests': ['a11yr', 08:43:52 INFO - 'ts_paint', 08:43:52 INFO - 'tpaint', 08:43:52 INFO - 'sessionrestore', 08:43:52 INFO - 'sessionrestore_no_auto_restore', 08:43:52 INFO - 'tabpaint']}, 08:43:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:43:52 INFO - 'ts_paint', 08:43:52 INFO - 'tpaint', 08:43:52 INFO - 'sessionrestore', 08:43:52 INFO - 'sessionrestore_no_auto_restore', 08:43:52 INFO - 'tabpaint']}, 08:43:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:43:52 INFO - 'ts_paint', 08:43:52 INFO - 'tpaint', 08:43:52 INFO - 'sessionrestore', 08:43:52 INFO - 'sessionrestore_no_auto_restore', 08:43:52 INFO - 'tabpaint']}, 08:43:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['a11yr', 08:43:52 INFO - 'ts_paint', 08:43:52 INFO - 'tpaint', 08:43:52 INFO - 'sessionrestore', 08:43:52 INFO - 'sessionrestore_no_auto_restore', 08:43:52 INFO - 'tabpaint']}, 08:43:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['a11yr', 08:43:52 INFO - 'ts_paint', 08:43:52 INFO - 'tpaint', 08:43:52 INFO - 'sessionrestore', 08:43:52 INFO - 'sessionrestore_no_auto_restore', 08:43:52 INFO - 'tabpaint']}, 08:43:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:43:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:43:52 INFO - 'bloom_basic_ref']}, 08:43:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:43:52 INFO - 'talos_options': ['--mitmproxy', 08:43:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:43:52 INFO - '--firstNonBlankPaint'], 08:43:52 INFO - 'tests': ['quantum_pageload_google', 08:43:52 INFO - 'quantum_pageload_youtube', 08:43:52 INFO - 'quantum_pageload_amazon', 08:43:52 INFO - 'quantum_pageload_facebook']}, 08:43:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['tsvgx', 08:43:52 INFO - 'tsvgr_opacity', 08:43:52 INFO - 'tart', 08:43:52 INFO - 'tscrollx', 08:43:52 INFO - 'cart', 08:43:52 INFO - 'tsvg_static']}, 08:43:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:43:52 INFO - 'tsvgr_opacity', 08:43:52 INFO - 'tart', 08:43:52 INFO - 'tscrollx', 08:43:52 INFO - 'cart', 08:43:52 INFO - 'tsvg_static']}, 08:43:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'talos_options': ['--disable-e10s'], 08:43:52 INFO - 'tests': ['tp5o']}, 08:43:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:43:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'talos_options': ['--disable-e10s', 08:43:52 INFO - '--xperf_path', 08:43:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:52 INFO - 'tests': ['tp5n']}, 08:43:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:43:52 INFO - 'talos_options': ['--xperf_path', 08:43:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:52 INFO - 'tests': ['tp5n']}}, 08:43:52 INFO - 'talos.zip': {'path': '', 08:43:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:43:52 INFO - Running post-action listener: _resource_record_post_action 08:43:52 INFO - [mozharness: 2017-06-16 15:43:52.931016Z] Finished populate-webroot step (success) 08:43:52 INFO - [mozharness: 2017-06-16 15:43:52.931144Z] Running create-virtualenv step. 08:43:52 INFO - Running pre-action listener: _resource_record_pre_action 08:43:52 INFO - Running main action method: create_virtualenv 08:43:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:43:52 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 08:43:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:43:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:43:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:43:53 INFO - Using real prefix '/tools/python27' 08:43:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:43:53 INFO - Installing distribute.............................................................................................................................................................................................done. 08:43:56 INFO - Installing pip.................done. 08:43:56 INFO - Return code: 0 08:43:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:43:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:43:56 INFO - Reading from file tmpfile_stdout 08:43:56 INFO - Output received: 08:43:56 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) 08:43:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:43:56 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')]} 08:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:56 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')]} 08:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:43:56 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 08:43:56 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 08:43:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:43:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:56 INFO - 'HOME': '/Users/cltbld', 08:43:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:56 INFO - 'LOGNAME': 'cltbld', 08:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:56 INFO - 'MOZ_NO_REMOTE': '1', 08:43:56 INFO - 'NO_EM_RESTART': '1', 08:43:56 INFO - 'PAGER': '/bin/cat', 08:43:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:56 INFO - 'PWD': '/builds/slave/test', 08:43:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:56 INFO - 'SHELL': '/bin/bash', 08:43:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:43:56 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:43:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:56 INFO - 'USER': 'cltbld', 08:43:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:56 INFO - 'XPC_FLAGS': '0x0', 08:43:56 INFO - 'XPC_SERVICE_NAME': '0', 08:43:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:57 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 08:43:57 INFO - Cleaning up... 08:43:57 INFO - Return code: 0 08:43:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:43:57 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')]} 08:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:57 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')]} 08:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:57 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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:43:57 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 08:43:57 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 08:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:57 INFO - 'HOME': '/Users/cltbld', 08:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:57 INFO - 'LOGNAME': 'cltbld', 08:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:57 INFO - 'MOZ_NO_REMOTE': '1', 08:43:57 INFO - 'NO_EM_RESTART': '1', 08:43:57 INFO - 'PAGER': '/bin/cat', 08:43:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:57 INFO - 'PWD': '/builds/slave/test', 08:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:57 INFO - 'SHELL': '/bin/bash', 08:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:43:57 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:57 INFO - 'USER': 'cltbld', 08:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:57 INFO - 'XPC_FLAGS': '0x0', 08:43:57 INFO - 'XPC_SERVICE_NAME': '0', 08:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:57 INFO - Downloading/unpacking psutil>=3.1.1 08:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:43:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:59 INFO - Installing collected packages: psutil 08:43:59 INFO - Running setup.py install for psutil 08:43:59 INFO - building 'psutil._psutil_osx' extension 08:43:59 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 08:44:00 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 08:44:00 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 08:44:00 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 08:44:00 INFO - building 'psutil._psutil_posix' extension 08:44:00 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 08:44:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:44:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:44:00 INFO - ^ 08:44:00 INFO - 1 warning generated. 08:44:00 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 08:44:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:00 INFO - Successfully installed psutil 08:44:00 INFO - Cleaning up... 08:44:01 INFO - Return code: 0 08:44:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:01 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')]} 08:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:01 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')]} 08:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:01 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 08:44:01 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 08:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:01 INFO - 'HOME': '/Users/cltbld', 08:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:01 INFO - 'LOGNAME': 'cltbld', 08:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:01 INFO - 'MOZ_NO_REMOTE': '1', 08:44:01 INFO - 'NO_EM_RESTART': '1', 08:44:01 INFO - 'PAGER': '/bin/cat', 08:44:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:01 INFO - 'PWD': '/builds/slave/test', 08:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:01 INFO - 'SHELL': '/bin/bash', 08:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:01 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:01 INFO - 'USER': 'cltbld', 08:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:01 INFO - 'XPC_FLAGS': '0x0', 08:44:01 INFO - 'XPC_SERVICE_NAME': '0', 08:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:01 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 08:44:01 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 08:44:01 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 08:44:01 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 08:44:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:02 INFO - Installing collected packages: mozsystemmonitor 08:44:02 INFO - Running setup.py install for mozsystemmonitor 08:44:02 INFO - Successfully installed mozsystemmonitor 08:44:02 INFO - Cleaning up... 08:44:02 INFO - Return code: 0 08:44:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:02 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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:02 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 08:44:02 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 08:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:02 INFO - 'HOME': '/Users/cltbld', 08:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:02 INFO - 'LOGNAME': 'cltbld', 08:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:02 INFO - 'MOZ_NO_REMOTE': '1', 08:44:02 INFO - 'NO_EM_RESTART': '1', 08:44:02 INFO - 'PAGER': '/bin/cat', 08:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:02 INFO - 'PWD': '/builds/slave/test', 08:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:02 INFO - 'SHELL': '/bin/bash', 08:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:02 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:02 INFO - 'USER': 'cltbld', 08:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:02 INFO - 'XPC_FLAGS': '0x0', 08:44:02 INFO - 'XPC_SERVICE_NAME': '0', 08:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:03 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:03 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 08:44:03 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 08:44:03 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 08:44:03 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 08:44:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:04 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 08:44:04 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 08:44:04 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 08:44:04 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 08:44:04 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:04 INFO - warning: no files found matching '*.txt' 08:44:04 INFO - no previously-included directories found matching 'build' 08:44:04 INFO - no previously-included directories found matching 'dist' 08:44:04 INFO - no previously-included directories found matching '.git*' 08:44:04 INFO - Installing collected packages: jsonschema, functools32 08:44:04 INFO - Running setup.py install for functools32 08:44:05 INFO - warning: no files found matching '*.txt' 08:44:05 INFO - no previously-included directories found matching 'build' 08:44:05 INFO - no previously-included directories found matching 'dist' 08:44:05 INFO - no previously-included directories found matching '.git*' 08:44:05 INFO - Successfully installed jsonschema functools32 08:44:05 INFO - Cleaning up... 08:44:05 INFO - Return code: 0 08:44:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:05 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')]} 08:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:05 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')]} 08:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:05 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 08:44:05 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 08:44:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:05 INFO - 'HOME': '/Users/cltbld', 08:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:05 INFO - 'LOGNAME': 'cltbld', 08:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:05 INFO - 'MOZ_NO_REMOTE': '1', 08:44:05 INFO - 'NO_EM_RESTART': '1', 08:44:05 INFO - 'PAGER': '/bin/cat', 08:44:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:05 INFO - 'PWD': '/builds/slave/test', 08:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:05 INFO - 'SHELL': '/bin/bash', 08:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:05 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:05 INFO - 'USER': 'cltbld', 08:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:05 INFO - 'XPC_FLAGS': '0x0', 08:44:05 INFO - 'XPC_SERVICE_NAME': '0', 08:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:05 INFO - Cleaning up... 08:44:05 INFO - Return code: 0 08:44:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:05 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')]} 08:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:05 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')]} 08:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:05 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 08:44:05 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 08:44:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:05 INFO - 'HOME': '/Users/cltbld', 08:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:05 INFO - 'LOGNAME': 'cltbld', 08:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:05 INFO - 'MOZ_NO_REMOTE': '1', 08:44:05 INFO - 'NO_EM_RESTART': '1', 08:44:05 INFO - 'PAGER': '/bin/cat', 08:44:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:05 INFO - 'PWD': '/builds/slave/test', 08:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:05 INFO - 'SHELL': '/bin/bash', 08:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:05 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:05 INFO - 'USER': 'cltbld', 08:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:05 INFO - 'XPC_FLAGS': '0x0', 08:44:05 INFO - 'XPC_SERVICE_NAME': '0', 08:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:05 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:05 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 08:44:05 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 08:44:05 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 08:44:05 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 08:44:07 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:07 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 08:44:07 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 08:44:07 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 08:44:07 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 08:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:07 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 08:44:07 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 08:44:07 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 08:44:07 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 08:44:08 INFO - Downloading docopt-0.6.1.tar.gz 08:44:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:08 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:08 INFO - Running setup.py install for blobuploader 08:44:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:08 INFO - Running setup.py install for requests 08:44:08 INFO - Running setup.py install for docopt 08:44:08 INFO - Successfully installed blobuploader requests docopt 08:44:08 INFO - Cleaning up... 08:44:09 INFO - Return code: 0 08:44:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:09 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')]} 08:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:09 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')]} 08:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:09 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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:09 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 08:44:09 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 08:44:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:09 INFO - 'HOME': '/Users/cltbld', 08:44:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:09 INFO - 'LOGNAME': 'cltbld', 08:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:09 INFO - 'MOZ_NO_REMOTE': '1', 08:44:09 INFO - 'NO_EM_RESTART': '1', 08:44:09 INFO - 'PAGER': '/bin/cat', 08:44:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:09 INFO - 'PWD': '/builds/slave/test', 08:44:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:09 INFO - 'SHELL': '/bin/bash', 08:44:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:09 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:09 INFO - 'USER': 'cltbld', 08:44:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:09 INFO - 'XPC_FLAGS': '0x0', 08:44:09 INFO - 'XPC_SERVICE_NAME': '0', 08:44:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:09 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-sjgLGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:09 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-ST66zI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:09 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-LjKTov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:09 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-bE72Rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:09 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-WUSx95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:09 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-59K5tP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:09 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-AMyUci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-HhqtB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-tbVLco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-oUuytb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Cw5YlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-NYO6Eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-OtUleO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-HJGBbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-md4ASH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-I4DGZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:10 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-0sZgLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:11 INFO - Running setup.py install for manifestparser 08:44:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:11 INFO - Running setup.py install for mozcrash 08:44:11 INFO - Running setup.py install for mozdebug 08:44:11 INFO - Running setup.py install for mozdevice 08:44:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:44:11 INFO - Running setup.py install for mozfile 08:44:11 INFO - Running setup.py install for mozhttpd 08:44:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:44:11 INFO - Running setup.py install for mozinfo 08:44:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:44:12 INFO - Running setup.py install for mozInstall 08:44:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:44:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:44:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:44:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:44:12 INFO - Running setup.py install for mozleak 08:44:12 INFO - Running setup.py install for mozlog 08:44:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:44:12 INFO - Running setup.py install for moznetwork 08:44:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:44:12 INFO - Running setup.py install for mozprocess 08:44:12 INFO - Running setup.py install for mozprofile 08:44:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:44:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:44:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:44:13 INFO - Running setup.py install for mozrunner 08:44:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:44:13 INFO - Running setup.py install for mozscreenshot 08:44:13 INFO - Running setup.py install for moztest 08:44:13 INFO - Running setup.py install for mozversion 08:44:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:44:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:44:13 INFO - Cleaning up... 08:44:13 INFO - Return code: 0 08:44:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:13 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')]} 08:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:13 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')]} 08:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:13 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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:13 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 08:44:13 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 08:44:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:13 INFO - 'HOME': '/Users/cltbld', 08:44:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:13 INFO - 'LOGNAME': 'cltbld', 08:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:13 INFO - 'MOZ_NO_REMOTE': '1', 08:44:13 INFO - 'NO_EM_RESTART': '1', 08:44:13 INFO - 'PAGER': '/bin/cat', 08:44:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:13 INFO - 'PWD': '/builds/slave/test', 08:44:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:13 INFO - 'SHELL': '/bin/bash', 08:44:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:13 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:13 INFO - 'USER': 'cltbld', 08:44:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:13 INFO - 'XPC_FLAGS': '0x0', 08:44:13 INFO - 'XPC_SERVICE_NAME': '0', 08:44:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:13 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-7rAG7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-J9iuJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-LDQCGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-077zcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-EW_J2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-XJ3fXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-oxDjgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-NHBMKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-6eCgQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:14 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)) 08:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:14 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-ThW_Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:15 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)) 08:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:15 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-NKhOhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:15 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)) 08:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:15 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-voKzl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:15 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)) 08:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:15 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-FlmqHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:15 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)) 08:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:15 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-SfB1Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:15 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)) 08:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:15 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-gwnJbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:15 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)) 08:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:15 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-OazKvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:15 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)) 08:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:15 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-DP9rdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:17 INFO - Downloading blessings-1.6.tar.gz 08:44:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:44:17 INFO - Installing collected packages: blessings 08:44:17 INFO - Running setup.py install for blessings 08:44:17 INFO - Successfully installed blessings 08:44:17 INFO - Cleaning up... 08:44:17 INFO - Return code: 0 08:44:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:44:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:17 INFO - Reading from file tmpfile_stdout 08:44:17 INFO - Current package versions: 08:44:17 INFO - blessings == 1.6 08:44:17 INFO - blobuploader == 1.2.4 08:44:17 INFO - docopt == 0.6.1 08:44:17 INFO - functools32 == 3.2.3-2 08:44:17 INFO - jsonschema == 2.5.1 08:44:17 INFO - manifestparser == 1.1 08:44:17 INFO - mozInstall == 1.13 08:44:17 INFO - mozcrash == 1.0 08:44:17 INFO - mozdebug == 0.1 08:44:17 INFO - mozdevice == 0.51 08:44:17 INFO - mozfile == 1.2 08:44:17 INFO - mozhttpd == 0.7 08:44:17 INFO - mozinfo == 0.10 08:44:17 INFO - mozleak == 0.1 08:44:17 INFO - mozlog == 3.4 08:44:17 INFO - moznetwork == 0.27 08:44:17 INFO - mozprocess == 0.25 08:44:17 INFO - mozprofile == 0.28 08:44:17 INFO - mozrunner == 6.13 08:44:17 INFO - mozscreenshot == 0.1 08:44:17 INFO - mozsystemmonitor == 0.3 08:44:17 INFO - moztest == 0.8 08:44:17 INFO - mozversion == 1.4 08:44:17 INFO - psutil == 3.1.1 08:44:17 INFO - requests == 1.2.3 08:44:17 INFO - wsgiref == 0.1.2 08:44:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:17 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')]} 08:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:17 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')]} 08:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:17 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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:17 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 08:44:17 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 08:44:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:17 INFO - 'HOME': '/Users/cltbld', 08:44:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:17 INFO - 'LOGNAME': 'cltbld', 08:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:17 INFO - 'MOZ_NO_REMOTE': '1', 08:44:17 INFO - 'NO_EM_RESTART': '1', 08:44:17 INFO - 'PAGER': '/bin/cat', 08:44:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:17 INFO - 'PWD': '/builds/slave/test', 08:44:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:17 INFO - 'SHELL': '/bin/bash', 08:44:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:17 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:17 INFO - 'USER': 'cltbld', 08:44:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:17 INFO - 'XPC_FLAGS': '0x0', 08:44:17 INFO - 'XPC_SERVICE_NAME': '0', 08:44:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:18 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)) 08:44:18 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)) 08:44:18 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)) 08:44:18 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)) 08:44:18 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)) 08:44:18 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)) 08:44:18 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)) 08:44:18 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)) 08:44:18 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)) 08:44:18 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:19 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:44:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:44:19 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)) 08:44:19 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)) 08:44:19 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)) 08:44:19 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)) 08:44:19 INFO - Installing collected packages: marionette-driver 08:44:19 INFO - Running setup.py install for marionette-driver 08:44:19 INFO - Successfully installed marionette-driver 08:44:19 INFO - Cleaning up... 08:44:19 INFO - Return code: 0 08:44:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44: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')]} 08:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:19 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 0x101b51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc77bd79560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, '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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 08:44:19 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 08:44:19 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 08:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:19 INFO - 'HOME': '/Users/cltbld', 08:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:19 INFO - 'LOGNAME': 'cltbld', 08:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:19 INFO - 'MOZ_NO_REMOTE': '1', 08:44:19 INFO - 'NO_EM_RESTART': '1', 08:44:19 INFO - 'PAGER': '/bin/cat', 08:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:19 INFO - 'PWD': '/builds/slave/test', 08:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:19 INFO - 'SHELL': '/bin/bash', 08:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:19 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:19 INFO - 'USER': 'cltbld', 08:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:19 INFO - 'XPC_FLAGS': '0x0', 08:44:19 INFO - 'XPC_SERVICE_NAME': '0', 08:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:44:20 INFO - Cleaning up... 08:44:20 INFO - Return code: 0 08:44:20 INFO - Running post-action listener: _resource_record_post_action 08:44:20 INFO - Running post-action listener: _start_resource_monitoring 08:44:20 INFO - Starting resource monitoring. 08:44:20 INFO - [mozharness: 2017-06-16 15:44:20.060564Z] Finished create-virtualenv step (success) 08:44:20 INFO - [mozharness: 2017-06-16 15:44:20.061355Z] Running install step. 08:44:20 INFO - Running pre-action listener: _resource_record_pre_action 08:44:20 INFO - Running main action method: install 08:44:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:20 INFO - Reading from file tmpfile_stdout 08:44:20 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:20 INFO - Reading from file tmpfile_stdout 08:44:20 INFO - Output received: 08:44:20 INFO - Usage: mozinstall [options] installer 08:44:20 INFO - Options: 08:44:20 INFO - -h, --help show this help message and exit 08:44:20 INFO - -d DEST, --destination=DEST 08:44:20 INFO - Directory to install application into. [default: 08:44:20 INFO - "/builds/slave/test"] 08:44:20 INFO - --app=APP Application being installed. [default: firefox] 08:44:20 INFO - mkdir: /builds/slave/test/build/application 08:44:20 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'] 08:44:20 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 08:44:41 INFO - Reading from file tmpfile_stdout 08:44:41 INFO - Output received: 08:44:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:44:41 INFO - Running post-action listener: _resource_record_post_action 08:44:41 INFO - [mozharness: 2017-06-16 15:44:41.596217Z] Finished install step (success) 08:44:41 INFO - [mozharness: 2017-06-16 15:44:41.596357Z] Running setup-mitmproxy step. 08:44:41 INFO - Running pre-action listener: _resource_record_pre_action 08:44:41 INFO - Running main action method: setup_mitmproxy 08:44:41 INFO - Skipping: mitmproxy is not required 08:44:41 INFO - Running post-action listener: _resource_record_post_action 08:44:41 INFO - [mozharness: 2017-06-16 15:44:41.597014Z] Finished setup-mitmproxy step (success) 08:44:41 INFO - [mozharness: 2017-06-16 15:44:41.597129Z] Running run-tests step. 08:44:41 INFO - Running pre-action listener: _resource_record_pre_action 08:44:41 INFO - Running main action method: run_tests 08:44:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:44:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:44:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:44:41 INFO - 2017-06-16 08:44:41.611 screenresolution[1631:8443] starting screenresolution argv=screenresolution get 08:44:41 INFO - 2017-06-16 08:44:41.614 screenresolution[1631:8443] Display 0: 1600x1200x32@60 08:44:41 INFO - 2017-06-16 08:44:41.620 screenresolution[1632:8446] starting screenresolution argv=screenresolution list 08:44:41 INFO - Available Modes on Display 0 08:44:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:44:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:44:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:44:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:44:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:44:41 INFO - Intel Iris: 08:44:41 INFO - Chipset Model: Intel Iris 08:44:41 INFO - Type: GPU 08:44:41 INFO - Bus: Built-In 08:44:41 INFO - VRAM (Dynamic, Max): 1536 MB 08:44:41 INFO - Vendor: Intel (0x8086) 08:44:41 INFO - Device ID: 0x0a2e 08:44:41 INFO - Revision ID: 0x0009 08:44:41 INFO - Displays: 08:44:41 INFO - Display: 08:44:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:44:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:44:41 INFO - Display Serial Number: 1600x1200 60 08:44:41 INFO - Main Display: Yes 08:44:41 INFO - Mirror: Off 08:44:41 INFO - Online: Yes 08:44:41 INFO - Rotation: Supported 08:44:41 INFO - Return code: 0 08:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:44:41 INFO - Python 2.7.3 08:44:41 INFO - Return code: 0 08:44:41 INFO - grabbing minidump binary from tooltool 08:44:41 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')]} 08:44:41 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 0x1018a7d30>, '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 0x101871cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101898be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:44:41 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 08:44:41 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 08:44:41 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 08:44:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:44:41 INFO - Return code: 0 08:44:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:44:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:44:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:44:41 INFO - ENV: RUST_BACKTRACE is now 1 08:44:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:44:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:44:41 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 08:44:41 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --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 08:44:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SvPJfzVrrv/Render', 08:44:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:41 INFO - 'HOME': '/Users/cltbld', 08:44:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:41 INFO - 'LOGNAME': 'cltbld', 08:44:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:41 INFO - 'MOZ_NO_REMOTE': '1', 08:44:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:41 INFO - 'NO_EM_RESTART': '1', 08:44:41 INFO - 'PAGER': '/bin/cat', 08:44:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:41 INFO - 'PWD': '/builds/slave/test', 08:44:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:41 INFO - 'RUST_BACKTRACE': '1', 08:44:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:44:41 INFO - 'SHELL': '/bin/bash', 08:44:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eR6C2gOhm/Listeners', 08:44:41 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 08:44:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:41 INFO - 'USER': 'cltbld', 08:44:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:41 INFO - 'XPC_FLAGS': '0x0', 08:44:41 INFO - 'XPC_SERVICE_NAME': '0', 08:44:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:41 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 08:44:42 INFO - mozversion application_buildid: 20170616074221 08:44:42 INFO - mozversion application_changeset: 2b0d662801c24270a80187c9444dd829ee06be4e 08:44:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:44:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:44:42 INFO - mozversion application_name: Firefox 08:44:42 INFO - mozversion application_remotingname: firefox-dev 08:44:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:44:42 INFO - mozversion application_vendor: Mozilla 08:44:42 INFO - mozversion application_version: 55.0 08:44:42 INFO - mozversion platform_buildid: 20170616074221 08:44:42 INFO - mozversion platform_changeset: 2b0d662801c24270a80187c9444dd829ee06be4e 08:44:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:44:42 INFO - mozversion platform_version: 55.0 08:44:42 INFO - using testdate: 1497627882 08:44:42 INFO - actual date: 1497627882 08:44:42 INFO - starting webserver on 'localhost:49227' 08:44:42 INFO - SUITE-START | Running 2 tests 08:44:42 INFO - TEST-START | dromaeo_css 08:44:42 INFO - operating with platform_type : mac_ 08:44:42 INFO - Initialising browser for dromaeo_css test... 08:44:42 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpQnRCX0/profile 08:44:42 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 08:45:15 INFO - TEST-INFO | 1642: killed by SIGTERM 08:45:15 INFO - Browser initialized. 08:45:15 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:15 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpQnRCX0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:17 INFO - PID 1651 | 2017-06-16 08:45:17.574 plugin-container[1652:8942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 08:45:17 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:17 INFO - PID 1651 | 2017-06-16 08:45:17.575 plugin-container[1652:8942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 08:45:17 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:17 INFO - PID 1651 | 2017-06-16 08:45:17.575 plugin-container[1652:8942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:18 INFO - PID 1651 | 2017-06-16 08:45:18.348 plugin-container[1653:8987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:45:18 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:18 INFO - PID 1651 | 2017-06-16 08:45:18.349 plugin-container[1653:8987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 08:45:18 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:18 INFO - PID 1651 | 2017-06-16 08:45:18.349 plugin-container[1653:8987] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:27 INFO - PID 1651 | RSS: Main: 215838720 08:45:27 INFO - PID 1651 | 08:48:33 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 08:48:35 INFO - PID 1651 | RSS: Main: 229453824 08:48:35 INFO - PID 1651 | 08:51:36 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 08:51:37 INFO - PID 1651 | RSS: Main: 240623616 08:51:37 INFO - PID 1651 | 08:54:39 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 08:54:39 INFO - PID 1651 | RSS: Main: 242597888 08:54:39 INFO - PID 1651 | 08:57:41 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 08:57:42 INFO - PID 1651 | RSS: Main: 245125120 08:57:42 INFO - PID 1651 | 09:00:44 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 09:00:45 INFO - PID 1651 | RSS: Main: 246382592 09:00:45 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 09:03:47 INFO - PID 1651 | RSS: Main: 244363264 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | __start_tp_report 09:03:47 INFO - PID 1651 | _x_x_mozilla_page_load 09:03:47 INFO - PID 1651 | _x_x_mozilla_page_load_details 09:03:47 INFO - PID 1651 | |i|pagename|runs| 09:03:47 INFO - PID 1651 | |0;dojo.html;3088.4286407014706;3546.219831637039;3624.2389098289373;3664.1389273520717;3694.833732482038;5555.3611334696525;6259.593126446778;6305.2433707955;6334.271558770743;6339.27098383686;6361.268454127777;6377.13908622337;6399.976003839387;6409.935900640992;6419.069234960928;4792.760361981905;5552.500274975261;5602.915956260659;5638.210650508932;5638.661680299195;5447;5525.723713814293;5613.326400831906;5623.7469313880765;5628.409017053192;5493.972530137364;5524.447555244484;5531.806386776443;5551;5579.8884022319735;5585.273914391144;5595.160725891108;5596.832095037155;5614.29821272341;5643.351014633328;4871.634627402959;5307.548858347045;5482.424345443711;5495.422980587061;5589.916151257735;6315.24217093947;6320.93679063208;6325.9878419453125;6326.715297811564;6334.081558174039;6607.3392660734025;6664.266930637626;6879.415249703781;6911;6912;5338.306020217386;6828.351306625863;6943.1407401490005;6956.373926346653;6998.650067496554;5739.512141467939;6063.605370764749;6498;6517.478601711851;6530.249021362509;6882.965585172042;6958.165020197609;6968;6969.16370035599;6980.616066116311;6713.462922966151;6803.251642319341;6833;6836.794896153124;6841.589504629638;6962.895556566555;6984.615846128511;6988.930110698828;6999.51003429755;7034.40207582351;6839.521233513706;6886.24251332353;6906;6908.723651053901;6913.1358580177475;6648.767293144813;6727.327267273234;6739.224989126215;6805.251422343538;6916.792496225121;4719.315699223662;5414.945850541523;5438.184272359178;5465.699386533779;5518.503334699816;4074.4703188585886;4860.95139048605;4927.7536123193695;4931.654784165074;4948.480409556944;6408.03879418091;6414.102025716404;6438.839029024218;6442.6778661066755;6456.612603243821;6949.7567585134275;6984.126984126984;7006.159260888726;7017.649117544102;7023.33278338557;6180.752769889244;6312.684365781693;6323.336049714806;6378.649174295366;6400.55196136266;6127.678242056309;6137.693115344215;6142.815715528542;6143.696113957266;6200.875982480416;7169.53398029116;7177.031100801325;7194.96402518005;7198.064251647253;7202.387797037101;6985.1967023790885;7013.228544860163;7028.6485675714985;7040.929590704028;7062.682179302046;6884.862302754017;7136.607486588287;7170.89243661356;7171.928280717126;7190.892136618059;6699.29657385987;6732.124823772878;6732.798016059526;6747.460203183831;6836.042953986545;5878.29460464747;6966.1988871280455;7009.369156775914;7042.43660507148;7043.718251269892;6772.356626120511;6781.593104413553;6783.4573234142135;6805.217399998865;6816.693248803716;6622.668866556556;6631.071649969104;6645.966770166118;6802.14973128359;6805.727770889307;6291.559590828598;6399.520035997226;6414.166158399565;6416.967915160394;6483.837904052436;6728.529002969745;6774.8645027100165;6786.185657721105;6791.966040169767;6807.387335139663;6412.166418365583;6566.967165164335;6595.472362211108;6617.205935287604;6627.668616569056 09:03:47 INFO - PID 1651 | |1;ext.html;821.5645707774878;842.473454091193;844.7127976487994;861.9310455163587;877.3726785348474;37565.81217093914;38161.23677526448;38233.42649860247;38260.80869595648;38344.42483362748;37489.250214995685;37600.623993760055;37641.435378469345;37722.245555088884;37813.432798508045;7369.4841361104745;7548.5848278344665;7552.735654252102;7562.62186890656;7586.848263034736;7424.220456852034;7501.5874126923;7521.473496855222;7537.623118844036;7544.622768861563;7507.887381689279;7508.69965201394;7514.361279291267;7518.323350898418;7529.32236098751;7479.813004674872;7995;8314.293285070717;8330.250277475056;8339.20777526134;8153.510789352657;8231.835363292701;8236.094029656733;8250.46371985819;8252.958735206286;36258.456123158176;36563.451548226796;36570;36579;36656.633433665585;3550.1834578046937;3600.981995090034;3607.134287770942;3607.512985746917;3615.2769446110883;29669.554956675453;29740;29848;29866.402671946656;29879.402411951858;3024.894128705507;3105.0374383940825;3135.451296023187;3140.0893740815136;3146.5594816725675;261098;264549;264962;330631;333446;38388.04029899219;46947.765261173474;47019.5298047022;47137;47468.5253147471;51117.74441127771;51163;51188.232176517384;51227;51263;15357.078575285519;15425.228738563026;15515.84484155144;15522.767158492632;15528.922355388377;12651.493940242288;12696;12735;12799.552015679592;12820;13784;13801.792973105412;13807.17156970585;13828.446862125402;13910.791338129937;6143.877122457526;6178.135061091451;6207.720652570645;6209.19280493542;6212.782552610637;961.5769061612868;979.2019504104132;982.29274922056;1011.585250047477;1013.4628646817199;19730;20578.38264852057;22469.648546615794;22913.427164321027;23146;1004.2417974429405;1011.3881101933224;1039.8908114648004;1072.8068947589509;1075.9193060520493;11558.364289963965;11612.361320127306;11726.648200553997;11769.411529423494;12025.037996960222;360933;363328;363406;363776;363833;53735.46264537383;54156;54188.645283868216;54275.72862135664;54316;1432.978505322421;1470.0811992504684;1524.542637208855;1533.6089297229357;1558.6259297768454;21905.411329209117;22161.55676886421;22466.438339041655;22676.88661556682;23882.641760373957;38030.619693802706;38256;38415.84752457431;38424.80787596044;38469.03827404337;287713;287843;288234;289051;289538;28461.861525538745;29239.83040678349;29273.414531709674;32379.51430728588;35239.29521409506;35029;41774.1645167101;41989.740307789594;43538;43628.69113926587;24807.62788558137;27800.165995020183;28357;29070.418591628473;30463.93376231821;29033.709662903944;29713.702862971957;30469.847650761603;30510.237244069074;30613.846930765205;32670.509942350407;36593.26813463769;36622.633773661924;36657.26685466329;36743.26513469769;26545.7345426551;27107.1867843965;30237;30308;31380.37239255248;23267.418314542272;24273.51452970966;24308.87845560761;24837.87581062083;24906.50186996286 09:03:47 INFO - PID 1651 | |2;jquery.html;479.9592034677053;521.9164933610621;527.174971170119;538.3701069748394;549.6125231711644;17367.565810854736;21833.781662183374;22230.333090007327;22758.886205568968;22991.310260692186;23808.166714165007;25516.617250741252;25614.743852561467;25658.973641054334;25667;6541.182352205962;6693.096431981693;6697.062411262427;6715.496337774662;6726.562773419737;6425.742970281207;6599;6634.104395906539;6639.568428052161;6676.666166691671;6186.752529898821;6485.8054258372085;6507.707153178095;6539.901901471458;6555.147830782015;6524.510661700344;6526.249481309639;6548.639824809634;6549;6554.868902621968;6035.517158627299;6038.426349496791;6044.486218671374;6053.818385448443;6064.06007068905;28086.2978425541;30377.392452151053;30635.540466893013;30703.385932281228;30846.22884427885;2302.3323236261463;2317.2932255662017;2335.311083230451;2343.5195784864063;2348.5068135691527;19071.237150513964;19557.902210489;19558.92425916574;19678.606427871364;19694.70457943132;2158.9892050539647;2200.8679479231296;2231.308294428716;2240.361496973355;2245.978079973608;199459.002704987;200921;203084.98457507766;204719;204946;15691.764623530657;15728.213589320718;15848.286827092808;15860.969037012557;16068.196590170444;20892.791072089083;20941.952902355126;21018.89490552568;21022.894885525475;21034.579308413748;10492;10587.4706264688;10607.098396636373;10613.097886679565;10614.787704245871;10040.397576145455;10044.096031357212;10073.345232559861;10083.142932850644;10144.746381340525;8922.420042697204;9254.12085851843;9314;9390.483523406278;9399.201067909167;2596.7143614202423;2602.2063270702615;2614.9215523534326;2627.4876399102372;2742.506348857185;842.6334544473174;913.7898283394857;945.7206142206434;1008.8297574813181;1021.4994652620163;18230.63538729218;18245.452636420927;19140.80859191418;22215.22246721357;22910.885445572665;987.9555420006117;1000.6797824696027;1052.7947050325113;1056.1761825775907;1056.3872953686844;9263.073692630818;9510.76360073186;11222.887771122185;11311.321320720783;11815.763684726257;564265;580345.0982745059;595876;604561.9543804594;606619.9669001626;142227;163888;164148;166695;166821;1146.599573626277;1189.2448295332354;1200.3158199826016;1200.4897918384652;1210.3706072842074;19223;19385.127669254634;20244.987750612705;20293.391198264075;20560.794392056487;70554.29445705477;71530.28469715237;71764.64117679378;72434.27565724275;74417;69498;93373;94544.52727736592;96565.5171724165;99019;11944.283342999448;12021.699457513283;12176.147669663038;12196.573119941115;12207.206531575774;13539.661508461973;16291.918540407221;18588.349407770664;18635.161417735962;18815.435536933914;14724.337404816595;14888.478903238334;14947.476838310607;14997.77503337429;15166.924165379103;12298.631041068782;12502;13146.34268286601;13556.661083472993;13840.4463821446;15064.246787660353;15456.459023934109;15525.146116963675;15586.610334741723;15698;11695.88304116948;11708.648740537457;11735.41322933867;11905.583304584297;11946.581869634521;14225.14649121056;14829.96190266671;14927.477538286108;14933.328000239799;15067.09597424158 09:03:47 INFO - PID 1651 | |3;mootools.html;1297.4745228182587;1459.1536908593016;1461.0576178364954;1469.5958611381873;1475.9778603320945;13657.931710341447;14199.935004874636;14218.146911185337;14220.288985550707;14244.216568088765;13582.524611638595;13952.2326272055;13962.720745585082;13970.16179029259;13975.580732578013;2511.573032584456;2751.857978938738;2790.7767378609715;2815.5635876439183;2833.150054983507;2356.4815740537056;2797.216779301799;2797.342624483255;2801.845898475583;2802.4675311690817;2649.986750066247;2768.709285525021;2850.714928507153;2856.214541001231;2856.971430285702;2814.085422237781;2849.7292757188034;2850.814697044696;2854.7145285471493;2861.298981749464;2808.8033837631374;2830.249983754307;2831.6318878545862;2835.4470878178763;2851.543752999531;13443.798343024764;13682.657933551642;13702.45190192391;13726.65683357914;13734.725305493834;7609.124950630693;7910;7917.881231781528;7926.0884998225365;7933.603319833986;4609.723416595015;4849.781759820817;4936.703797772109;4946.5795407390415;4955.959248557807;1419.8935079868947;1422.5518961527087;1422.6230049037013;1446.8263808343065;1448.5509492057497;17762.733558996624;21067;21081.262155824472;21189;21243;6628.701708423133;6783.796486105425;6858.279880612564;6901.965490172517;6920.861582768417;14424.206668633116;14443.422263109567;14448.422063117569;14457.92771036138;14497.202653853928;7586;7691.730789422343;7695.6152192390155;7697.191794861459;7774.1837107104075;6751.345920249472;6997.615131167835;7051.964740176266;7112.466565007541;7123.287671232939;5228.928069745693;5288.81489147878;5298.867528311823;5349.117395629755;5377.5429088527135;1508.6077619818707;1529.3653133949285;1532.4559781277485;1542.7840102385676;1561.8594326510668;608.2640005593282;750.4821673045581;790.0319426495263;849.426637020009;851.4082712514867;7957.403194760301;8025.077116131602;8038.236367545191;8380.120087390858;8410.453320534145;954.9713508594614;959.5010594490826;964.252704154286;1001.9448930308757;1029.711680729397;4819;4912.410510738734;5037.29477873098;5074.974625126924;5235.921461178085;18268.086595670164;18586.721199182022;18838.529036773914;18857.339993100177;18870.056497175086;18072.819271807377;18175.636487270443;18321.81678183201;18511.81488185101;18687.43937681872;9576;9713.320067595201;9782.85325720129;9802.95098524517;9824.705258842247;7489.438292128003;7527.510711803715;8159.714409995621;8211.3430925527;8229.95885020571;13707.314634268447;16084.35662573484;16316.42892498757;16804.075775832447;16824.74762878533;16992.830071699125;17676.82323176803;17695.115244237993;17743;17763;10804.945975270388;10909.890901090888;10964.396958167377;11032.245183188772;11119.388433636228;12482.812757808822;12615;12615.495380185057;12634.620961371174;12669.366531673564;9905.702828915144;10100.040496152853;10143.391396516232;10165.695029148841;10263.178945684178;9745.415275083518;9795.755106122118;9856.5564549594;10161.745956351151;10340.37957722539;9347.345685801929;9374.718758437259;9460.526973651427;9488.240940724587;9509.952450237704;6612.867742645024;6749.0888730020815;6792.1849378072975;6816.897746533905;7112.324329188718;5854.385289544707;6922.550034247758;7250.274972502708;7318.1949985502615;7334.083239595051 09:03:47 INFO - PID 1651 | |4;prototype.html;410.97051885028003;426.51164416742847;445.68356466908494;463.5758281172727;474.0187811230749;5871.383504732005;6352.428281454668;6471.482281417487;6474.190726159236;6493.707783149758;4951.3563236779255;5490.298007617641;5930.140129681194;6024.48791852693;6343.175387199658;4563.977180114103;4713.892235324696;4735.384400027991;4741.075490279397;4761.809527618909;4152.584741525853;4359.280718681413;4703.529647035303;4734.9763251183695;4740.549647783455;3767.849286028569;4711.246200607903;4722.291656251573;4735.73953432561;4820;3960.3663413853646;4462.509123996374;4531.56950089741;4718.834840780591;4781.613332133677;3343.3981883261004;3480.7911525308564;4135.97932010338;4145.937810932838;4151.543330233702;5407.459254074601;5419.756110975016;6110.908336374958;6190.257169139687;6239;5183.922241166386;5342.759575819058;5637.746301416405;5641.435856414367;5688.146777983295;5634.830955071355;6183;6737.528373013891;6797.694103765342;6818.795436136924;4754.310624959361;4756.833510827105;4773.856784296477;4776.331313616097;4781.258904869716;6219.657918814463;6678.597494526155;7061;7138.430784607696;7242.98598196253;4495.527969563149;4636.698614590108;4779.95220047802;4855.465898751135;4884.8290309838985;4363.734516990101;4937.35814344133;4940.060663904971;4953.6037117030555;4958.603311735053;5086;5291.867703307448;5609.635373700697;5627.831165064973;5657.773689052474;5819.854503637443;6816.727330906808;6819.624920629314;6893;6936.722531098801;1529.1895295493407;1685.9072750998569;1700.4643537285715;1700.6428649983395;1708.128854284299;1033.7415646088477;1107.8423047914948;1133.7845809296207;1139.1114930354338;1149.6263714292888;4986.376702912136;6347.0479428086155;6365.2679941806355;6516.902246466307;6578.76974305897;5959.433853783884;6594.670266486656;6898.206706228848;6913.3777959983845;7072.787816365517;5193.636445448858;5734.369219385864;6316.199883033313;6447.259914536397;6453.903191452132;3213.750374737693;3294.5058241263523;3295.1432627516538;3368.5789276340456;3687.3547129252274;5743.511801496836;7241.637918104074;7262.491625586264;7310.4151667866445;7341.119065712149;6310.643211709505;6911.9308806912295;7028.894566581505;7200.963995179991;7297.525660832135;5732;5735.885282294247;6029.8191054268445;7093.0424392707355;7242.311980361963;7055.3650171484815;7061.540999834994;7081.221065682874;7086.007958885657;7101.609411482418;4064.8577299795547;6921.134858142732;6966.477514186355;6978.651067446709;6979.965100174467;6225.817094751983;6752.818256805138;7160.283971602798;7231.674574644258;7238.647439582051;5105;5401.771039813414;5429.864253393696;5434.10337294342;5449.536789372789;6746.392824645805;7061.752838650623;7101.786946391617;7104.183018952886;7126.251743566851;6272.655003974825;6549.541532092899;6798.388145066968;6815.386615204656;6858.6227757471825;6007.339192688713;6228.626282423069;6474.931636280149;6818.727250910107;6865.862682746417;6691.665416729242;6701.530892837454;6704.66476676174;6704.793137235344;6720.630365329758;6841.126726774866;6849.691763870737;6861.828454288683;6865.965670171817;6951.617661028692;6005.849853753691;6152.384761523812;6664.933350666625;6681.5656982296;6705.597664140168 09:03:47 INFO - PID 1651 | |5;yui.html;755.5504474837471;812.3420029775885;841.436237720727;857.2199298638241;863.1756672377876;13513.797293040612;17174.39889603864;17210.741838872425;17212.48362549124;17235.052072136026;15763.60590985226;16472;17083.974961502317;17650.38223662172;18813.341533046347;993.4635296939662;1003.282652903174;1019.306871327497;1026.3892983674702;1028.88682244953;957.0812020460367;1013.1388319928076;1026.379040680389;1026.7073883943078;1028.5731421460084;940.1397721085199;998.4857798233916;1009.0072107587492;1046.0376453662611;1047.350642601588;1025.9692209233735;1032.633415137623;1041.494874985635;1045.890181530936;1051.159072741811;771.2595907928395;779.3920741821388;779.7193010516266;785.9882101768478;793.0562630469723;16421.343146274136;16556;16556.172191390382;16592.336306547724;16870.915645421817;17034.914825425916;17722.822771772248;17756.934583925005;17940.641187176316;18152.818471815244;14897.329620167011;14909.329080191417;14939.25303734809;15015.399384024626;15053.247337633074;3592.2456284180353;3613.8554457821656;3621.149029977953;3624.5650521937273;3644.7813131211947;125386.37306813408;128000.35999820035;129123;131106;132521;13072.738545229042;13503.797443038362;13727.931360343135;14450.277486125651;14473.78289325661;26610.733892661214;27090.78091485888;27182.04862829831;27252.454950900872;27400.451990960468;8028.638711258008;8323.542205178652;8324.417290789708;8331.750047498585;8401;3780.5085338905765;3827.770333779982;3860.768353898775;3861.0733423978604;3867.45855580219;4719.244920812654;4775.331453596499;4810.086083644096;4817.855464336076;4823.710577365369;888.657866721304;937.437537477508;1040.70339953113;1042.489180301662;1051.8579991701176;607.9331273559905;655.3250152343094;656.3502132888406;675.8175292670951;676.1818199977984;10796.460176991119;11475.942620287013;11689.76620467586;11933.761324773455;12474.378330816935;629.719774700254;643.7585905285518;653.4641593892962;680.2415306932735;680.7106979533678;5573.860653483614;5580.972095139498;6206.317305096436;6356.9364306357265;6712.295209003082;141593;142623;143843.2807835975;148184.2590787039;153166;112416;123973;124940;125047;125214.3739281316;99463.50804737935;101763.47354789832;104878.47560762147;107913;113742.43128784468;12137.757244854876;12505.874471297628;12572.559960401342;12621;13196.14225075367;44157.55842441534;44254.55745442633;44360.77819610881;47227.76386118047;48141.75929120472;77767;85432;86199;88243.11756882606;89187;17453.47639570815;17691.73462398091;17789.46631601054;18385.356512521998;18514.44456666302;18094;18879.716804247673;19217.423477295702;19278.421647350602;19546.60906781885;17771.73342399891;17981.910090449463;18243.635127297115;18432.539186520444;18466.446006619823;17420.47738567845;17738.467845964642;17788.110594469967;17920.551986200448;18539.44381668552;14482.927585362006;17021.40425085116;17142.742858857375;18104.547386315448;18140.546486337946;11829.290242585472;11956.940215298868;12155.270683759003;12167.878321216675;12215.938920305342;13707.451701931812;14464.493742718954;14571.34428950721;14638;14672.706545869236 09:03:47 INFO - PID 1651 | __end_tp_report 09:03:47 INFO - PID 1651 | __start_cc_report 09:03:47 INFO - PID 1651 | _x_x_mozilla_cycle_collect,399 09:03:47 INFO - PID 1651 | __end_cc_report 09:03:47 INFO - PID 1651 | __startTimestamp1497629027495__endTimestamp 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | ------- Summary: start ------- 09:03:47 INFO - PID 1651 | Number of tests: 6 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6304.55 Median:6530.25 stddev:807.63 (12.4%) stddev-sans-first:769.69 09:03:47 INFO - PID 1651 | Values: 3088.4 3546.2 3624.2 3664.1 3694.8 5555.4 6259.6 6305.2 6334.3 6339.3 6361.3 6377.1 6400.0 6409.9 6419.1 4792.8 5552.5 5602.9 5638.2 5638.7 5447.0 5525.7 5613.3 5623.7 5628.4 5494.0 5524.4 5531.8 5551.0 5579.9 5585.3 5595.2 5596.8 5614.3 5643.4 4871.6 5307.5 5482.4 5495.4 5589.9 6315.2 6320.9 6326.0 6326.7 6334.1 6607.3 6664.3 6879.4 6911.0 6912.0 5338.3 6828.4 6943.1 6956.4 6998.7 5739.5 6063.6 6498.0 6517.5 6530.2 6883.0 6958.2 6968.0 6969.2 6980.6 6713.5 6803.3 6833.0 6836.8 6841.6 6962.9 6984.6 6988.9 6999.5 7034.4 6839.5 6886.2 6906.0 6908.7 6913.1 6648.8 6727.3 6739.2 6805.3 6916.8 4719.3 5414.9 5438.2 5465.7 5518.5 4074.5 4861.0 4927.8 4931.7 4948.5 6408.0 6414.1 6438.8 6442.7 6456.6 6949.8 6984.1 7006.2 7017.6 7023.3 6180.8 6312.7 6323.3 6378.6 6400.6 6127.7 6137.7 6142.8 6143.7 6200.9 7169.5 7177.0 7195.0 7198.1 7202.4 6985.2 7013.2 7028.6 7040.9 7062.7 6884.9 7136.6 7170.9 7171.9 7190.9 6699.3 6732.1 6732.8 6747.5 6836.0 5878.3 6966.2 7009.4 7042.4 7043.7 6772.4 6781.6 6783.5 6805.2 6816.7 6622.7 6631.1 6646.0 6802.1 6805.7 6291.6 6399.5 6414.2 6417.0 6483.8 6728.5 6774.9 6786.2 6792.0 6807.4 6412.2 6567.0 6595.5 6617.2 6627.7 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:45763.16 Median:3604.06 stddev:83361.48 (2313.0%) stddev-sans-first:83527.04 09:03:47 INFO - PID 1651 | Values: 821.6 842.5 844.7 861.9 877.4 37565.8 38161.2 38233.4 38260.8 38344.4 37489.3 37600.6 37641.4 37722.2 37813.4 7369.5 7548.6 7552.7 7562.6 7586.8 7424.2 7501.6 7521.5 7537.6 7544.6 7507.9 7508.7 7514.4 7518.3 7529.3 7479.8 7995.0 8314.3 8330.3 8339.2 8153.5 8231.8 8236.1 8250.5 8253.0 36258.5 36563.5 36570.0 36579.0 36656.6 3550.2 3601.0 3607.1 3607.5 3615.3 29669.6 29740.0 29848.0 29866.4 29879.4 3024.9 3105.0 3135.5 3140.1 3146.6 261098.0 264549.0 264962.0 330631.0 333446.0 38388.0 46947.8 47019.5 47137.0 47468.5 51117.7 51163.0 51188.2 51227.0 51263.0 15357.1 15425.2 15515.8 15522.8 15528.9 12651.5 12696.0 12735.0 12799.6 12820.0 13784.0 13801.8 13807.2 13828.4 13910.8 6143.9 6178.1 6207.7 6209.2 6212.8 961.6 979.2 982.3 1011.6 1013.5 19730.0 20578.4 22469.6 22913.4 23146.0 1004.2 1011.4 1039.9 1072.8 1075.9 11558.4 11612.4 11726.6 11769.4 12025.0 360933.0 363328.0 363406.0 363776.0 363833.0 53735.5 54156.0 54188.6 54275.7 54316.0 1433.0 1470.1 1524.5 1533.6 1558.6 21905.4 22161.6 22466.4 22676.9 23882.6 38030.6 38256.0 38415.8 38424.8 38469.0 287713.0 287843.0 288234.0 289051.0 289538.0 28461.9 29239.8 29273.4 32379.5 35239.3 35029.0 41774.2 41989.7 43538.0 43628.7 24807.6 27800.2 28357.0 29070.4 30463.9 29033.7 29713.7 30469.8 30510.2 30613.8 32670.5 36593.3 36622.6 36657.3 36743.3 26545.7 27107.2 30237.0 30308.0 31380.4 23267.4 24273.5 24308.9 24837.9 24906.5 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:40910.62 Median:21020.89 stddev:102345.34 (486.9%) stddev-sans-first:102587.43 09:03:47 INFO - PID 1651 | Values: 480.0 521.9 527.2 538.4 549.6 17367.6 21833.8 22230.3 22758.9 22991.3 23808.2 25516.6 25614.7 25659.0 25667.0 6541.2 6693.1 6697.1 6715.5 6726.6 6425.7 6599.0 6634.1 6639.6 6676.7 6186.8 6485.8 6507.7 6539.9 6555.1 6524.5 6526.2 6548.6 6549.0 6554.9 6035.5 6038.4 6044.5 6053.8 6064.1 28086.3 30377.4 30635.5 30703.4 30846.2 2302.3 2317.3 2335.3 2343.5 2348.5 19071.2 19557.9 19558.9 19678.6 19694.7 2159.0 2200.9 2231.3 2240.4 2246.0 199459.0 200921.0 203085.0 204719.0 204946.0 15691.8 15728.2 15848.3 15861.0 16068.2 20892.8 20942.0 21018.9 21022.9 21034.6 10492.0 10587.5 10607.1 10613.1 10614.8 10040.4 10044.1 10073.3 10083.1 10144.7 8922.4 9254.1 9314.0 9390.5 9399.2 2596.7 2602.2 2614.9 2627.5 2742.5 842.6 913.8 945.7 1008.8 1021.5 18230.6 18245.5 19140.8 22215.2 22910.9 988.0 1000.7 1052.8 1056.2 1056.4 9263.1 9510.8 11222.9 11311.3 11815.8 564265.0 580345.1 595876.0 604562.0 606620.0 142227.0 163888.0 164148.0 166695.0 166821.0 1146.6 1189.2 1200.3 1200.5 1210.4 19223.0 19385.1 20245.0 20293.4 20560.8 70554.3 71530.3 71764.6 72434.3 74417.0 69498.0 93373.0 94544.5 96565.5 99019.0 11944.3 12021.7 12176.1 12196.6 12207.2 13539.7 16291.9 18588.3 18635.2 18815.4 14724.3 14888.5 14947.5 14997.8 15166.9 12298.6 12502.0 13146.3 13556.7 13840.4 15064.2 15456.5 15525.1 15586.6 15698.0 11695.9 11708.6 11735.4 11905.6 11946.6 14225.1 14830.0 14927.5 14933.3 15067.1 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:8457.03 Median:2822.91 stddev:5578.53 (197.6%) stddev-sans-first:5568.23 09:03:47 INFO - PID 1651 | Values: 1297.5 1459.2 1461.1 1469.6 1476.0 13657.9 14199.9 14218.1 14220.3 14244.2 13582.5 13952.2 13962.7 13970.2 13975.6 2511.6 2751.9 2790.8 2815.6 2833.2 2356.5 2797.2 2797.3 2801.8 2802.5 2650.0 2768.7 2850.7 2856.2 2857.0 2814.1 2849.7 2850.8 2854.7 2861.3 2808.8 2830.2 2831.6 2835.4 2851.5 13443.8 13682.7 13702.5 13726.7 13734.7 7609.1 7910.0 7917.9 7926.1 7933.6 4609.7 4849.8 4936.7 4946.6 4956.0 1419.9 1422.6 1422.6 1446.8 1448.6 17762.7 21067.0 21081.3 21189.0 21243.0 6628.7 6783.8 6858.3 6902.0 6920.9 14424.2 14443.4 14448.4 14457.9 14497.2 7586.0 7691.7 7695.6 7697.2 7774.2 6751.3 6997.6 7052.0 7112.5 7123.3 5228.9 5288.8 5298.9 5349.1 5377.5 1508.6 1529.4 1532.5 1542.8 1561.9 608.3 750.5 790.0 849.4 851.4 7957.4 8025.1 8038.2 8380.1 8410.5 955.0 959.5 964.3 1001.9 1029.7 4819.0 4912.4 5037.3 5075.0 5235.9 18268.1 18586.7 18838.5 18857.3 18870.1 18072.8 18175.6 18321.8 18511.8 18687.4 9576.0 9713.3 9782.9 9803.0 9824.7 7489.4 7527.5 8159.7 8211.3 8230.0 13707.3 16084.4 16316.4 16804.1 16824.7 16992.8 17676.8 17695.1 17743.0 17763.0 10804.9 10909.9 10964.4 11032.2 11119.4 12482.8 12615.0 12615.5 12634.6 12669.4 9905.7 10100.0 10143.4 10165.7 10263.2 9745.4 9795.8 9856.6 10161.7 10340.4 9347.3 9374.7 9460.5 9488.2 9510.0 6612.9 6749.1 6792.2 6816.9 7112.3 5854.4 6922.6 7250.3 7318.2 7334.1 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5473.06 Median:5982.64 stddev:1697.76 (28.4%) stddev-sans-first:1659.46 09:03:47 INFO - PID 1651 | Values: 411.0 426.5 445.7 463.6 474.0 5871.4 6352.4 6471.5 6474.2 6493.7 4951.4 5490.3 5930.1 6024.5 6343.2 4564.0 4713.9 4735.4 4741.1 4761.8 4152.6 4359.3 4703.5 4735.0 4740.5 3767.8 4711.2 4722.3 4735.7 4820.0 3960.4 4462.5 4531.6 4718.8 4781.6 3343.4 3480.8 4136.0 4145.9 4151.5 5407.5 5419.8 6110.9 6190.3 6239.0 5183.9 5342.8 5637.7 5641.4 5688.1 5634.8 6183.0 6737.5 6797.7 6818.8 4754.3 4756.8 4773.9 4776.3 4781.3 6219.7 6678.6 7061.0 7138.4 7243.0 4495.5 4636.7 4780.0 4855.5 4884.8 4363.7 4937.4 4940.1 4953.6 4958.6 5086.0 5291.9 5609.6 5627.8 5657.8 5819.9 6816.7 6819.6 6893.0 6936.7 1529.2 1685.9 1700.5 1700.6 1708.1 1033.7 1107.8 1133.8 1139.1 1149.6 4986.4 6347.0 6365.3 6516.9 6578.8 5959.4 6594.7 6898.2 6913.4 7072.8 5193.6 5734.4 6316.2 6447.3 6453.9 3213.8 3294.5 3295.1 3368.6 3687.4 5743.5 7241.6 7262.5 7310.4 7341.1 6310.6 6911.9 7028.9 7201.0 7297.5 5732.0 5735.9 6029.8 7093.0 7242.3 7055.4 7061.5 7081.2 7086.0 7101.6 4064.9 6921.1 6966.5 6978.7 6980.0 6225.8 6752.8 7160.3 7231.7 7238.6 5105.0 5401.8 5429.9 5434.1 5449.5 6746.4 7061.8 7101.8 7104.2 7126.3 6272.7 6549.5 6798.4 6815.4 6858.6 6007.3 6228.6 6474.9 6818.7 6865.9 6691.7 6701.5 6704.7 6704.8 6720.6 6841.1 6849.7 6861.8 6866.0 6951.6 6005.8 6152.4 6664.9 6681.6 6705.6 09:03:47 INFO - PID 1651 | 09:03:47 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:25922.29 Median:17747.70 stddev:38874.92 (219.0%) stddev-sans-first:38938.04 09:03:47 INFO - PID 1651 | Values: 755.6 812.3 841.4 857.2 863.2 13513.8 17174.4 17210.7 17212.5 17235.1 15763.6 16472.0 17084.0 17650.4 18813.3 993.5 1003.3 1019.3 1026.4 1028.9 957.1 1013.1 1026.4 1026.7 1028.6 940.1 998.5 1009.0 1046.0 1047.4 1026.0 1032.6 1041.5 1045.9 1051.2 771.3 779.4 779.7 786.0 793.1 16421.3 16556.0 16556.2 16592.3 16870.9 17034.9 17722.8 17756.9 17940.6 18152.8 14897.3 14909.3 14939.3 15015.4 15053.2 3592.2 3613.9 3621.1 3624.6 3644.8 125386.4 128000.4 129123.0 131106.0 132521.0 13072.7 13503.8 13727.9 14450.3 14473.8 26610.7 27090.8 27182.0 27252.5 27400.5 8028.6 8323.5 8324.4 8331.8 8401.0 3780.5 3827.8 3860.8 3861.1 3867.5 4719.2 4775.3 4810.1 4817.9 4823.7 888.7 937.4 1040.7 1042.5 1051.9 607.9 655.3 656.4 675.8 676.2 10796.5 11475.9 11689.8 11933.8 12474.4 629.7 643.8 653.5 680.2 680.7 5573.9 5581.0 6206.3 6356.9 6712.3 141593.0 142623.0 143843.3 148184.3 153166.0 112416.0 123973.0 124940.0 125047.0 125214.4 99463.5 101763.5 104878.5 107913.0 113742.4 12137.8 12505.9 12572.6 12621.0 13196.1 44157.6 44254.6 44360.8 47227.8 48141.8 77767.0 85432.0 86199.0 88243.1 89187.0 17453.5 17691.7 17789.5 18385.4 18514.4 18094.0 18879.7 19217.4 19278.4 19546.6 17771.7 17981.9 18243.6 18432.5 18466.4 17420.5 17738.5 17788.1 17920.6 18539.4 14482.9 17021.4 17142.7 18104.5 18140.5 11829.3 11956.9 12155.3 12167.9 12215.9 13707.5 14464.5 14571.3 14638.0 14672.7 09:03:47 INFO - PID 1651 | -------- Summary: end -------- 09:03:47 INFO - PID 1651 | 09:03:48 INFO - TEST-INFO | 1651: exit 0 09:03:48 INFO - TEST-OK | dromaeo_css | took 1145865ms 09:03:48 INFO - TEST-START | kraken 09:03:48 INFO - operating with platform_type : mac_ 09:03:48 INFO - Initialising browser for kraken test... 09:03:48 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpx0JKas/profile 09:03:48 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 09:04:21 INFO - TEST-INFO | 1661: killed by SIGTERM 09:04:21 INFO - Browser initialized. 09:04:21 INFO - Running cycle 1/1 for kraken test... 09:04:21 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpx0JKas/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:23 INFO - PID 1665 | 2017-06-16 09:04:23.403 plugin-container[1666:11398] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 09:04:23 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:23 INFO - PID 1665 | 2017-06-16 09:04:23.403 plugin-container[1666:11398] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 09:04:23 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:23 INFO - PID 1665 | 2017-06-16 09:04:23.404 plugin-container[1666:11398] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:24 INFO - PID 1665 | 2017-06-16 09:04:24.129 plugin-container[1667:11444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x922b, name = 'com.apple.tsm.portname' 09:04:24 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:24 INFO - PID 1665 | 2017-06-16 09:04:24.130 plugin-container[1667:11444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9517, name = 'com.apple.CFPasteboardClient' 09:04:24 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:24 INFO - PID 1665 | 2017-06-16 09:04:24.130 plugin-container[1667:11444] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:33 INFO - PID 1665 | RSS: Main: 214908928 09:04:33 INFO - PID 1665 | 09:04:36 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:37 INFO - PID 1665 | RSS: Main: 228573184 09:04:37 INFO - PID 1665 | 09:04:39 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 09:04:40 INFO - PID 1665 | RSS: Main: 238673920 09:04:40 INFO - PID 1665 | 09:04:43 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 09:04:44 INFO - PID 1665 | RSS: Main: 244002816 09:04:44 INFO - PID 1665 | 09:04:46 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 09:04:46 INFO - PID 1665 | RSS: Main: 244957184 09:04:46 INFO - PID 1665 | 09:04:48 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:49 INFO - PID 1665 | RSS: Main: 244854784 09:04:49 INFO - PID 1665 | 09:04:52 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:53 INFO - PID 1665 | RSS: Main: 248803328 09:04:53 INFO - PID 1665 | 09:04:56 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:56 INFO - PID 1665 | RSS: Main: 248995840 09:04:56 INFO - PID 1665 | 09:04:59 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 09:05:00 INFO - PID 1665 | RSS: Main: 249692160 09:05:00 INFO - PID 1665 | 09:05:02 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:05:02 INFO - PID 1665 | RSS: Main: 249356288 09:05:02 INFO - PID 1665 | 09:05:04 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:05:05 INFO - PID 1665 | RSS: Main: 250580992 09:05:05 INFO - PID 1665 | 09:05:07 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:05:07 INFO - PID 1665 | RSS: Main: 251052032 09:05:07 INFO - PID 1665 | 09:05:10 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:05:11 INFO - PID 1665 | RSS: Main: 250458112 09:05:11 INFO - PID 1665 | 09:05:14 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:14 INFO - PID 1665 | RSS: Main: 251146240 09:05:14 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 09:05:16 INFO - PID 1665 | RSS: Main: 253022208 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | __start_tp_report 09:05:16 INFO - PID 1665 | _x_x_mozilla_page_load 09:05:16 INFO - PID 1665 | _x_x_mozilla_page_load_details 09:05:16 INFO - PID 1665 | |i|pagename|runs| 09:05:16 INFO - PID 1665 | |0;ai-astar;102;115;106;120;118;116;115;101;116;115 09:05:16 INFO - PID 1665 | |1;audio-beat-detection;142;113;158;177;103;127;110;146;150;113 09:05:16 INFO - PID 1665 | |2;audio-dft;197;201;201;218;279;208;193;200;204;198 09:05:16 INFO - PID 1665 | |3;audio-fft;65;79;80;158;81;84;84;75;122;57 09:05:16 INFO - PID 1665 | |4;audio-oscillator;69;71;119;63;95;111;135;98;71;107 09:05:16 INFO - PID 1665 | |5;imaging-gaussian-blur;113;89;90;89;89;89;89;89;91;90 09:05:16 INFO - PID 1665 | |6;imaging-darkroom;97;98;116;151;96;95;98;97;97;97 09:05:16 INFO - PID 1665 | |7;imaging-desaturate;94;90;90;120;146;89;130;89;92;90 09:05:16 INFO - PID 1665 | |8;json-parse-financial;49;49;49;52;49;52;53;54;53;51 09:05:16 INFO - PID 1665 | |9;json-stringify-tinderbox;51;57;53;56;58;59;54;54;55;55 09:05:16 INFO - PID 1665 | |10;stanford-crypto-aes;81;79;79;82;82;77;83;80;71;80 09:05:16 INFO - PID 1665 | |11;stanford-crypto-ccm;133;188;147;144;152;150;132;160;154;160 09:05:16 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;179;175;179;178;180;176;207;208;177;174 09:05:16 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;67;64;63;67;68;65;66;66;67;87 09:05:16 INFO - PID 1665 | __end_tp_report 09:05:16 INFO - PID 1665 | __start_cc_report 09:05:16 INFO - PID 1665 | _x_x_mozilla_cycle_collect,1036 09:05:16 INFO - PID 1665 | __end_cc_report 09:05:16 INFO - PID 1665 | __startTimestamp1497629116880__endTimestamp 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | ------- Summary: start ------- 09:05:16 INFO - PID 1665 | Number of tests: 14 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:112.40 Median:115.50 stddev:6.79 (5.9%) stddev-sans-first:6.06 09:05:16 INFO - PID 1665 | Values: 102.0 115.0 106.0 120.0 118.0 116.0 115.0 101.0 116.0 115.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:144.00 stddev:24.40 (16.9%) stddev-sans-first:25.70 09:05:16 INFO - PID 1665 | Values: 142.0 113.0 158.0 177.0 103.0 127.0 110.0 146.0 150.0 113.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:209.90 Median:202.50 stddev:25.23 (12.5%) stddev-sans-first:26.32 09:05:16 INFO - PID 1665 | Values: 197.0 201.0 201.0 218.0 279.0 208.0 193.0 200.0 204.0 198.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:88.50 Median:79.50 stddev:29.70 (37.4%) stddev-sans-first:30.26 09:05:16 INFO - PID 1665 | Values: 65.0 79.0 80.0 158.0 81.0 84.0 84.0 75.0 122.0 57.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:70.00 stddev:24.55 (35.1%) stddev-sans-first:24.33 09:05:16 INFO - PID 1665 | Values: 69.0 71.0 119.0 63.0 95.0 111.0 135.0 98.0 71.0 107.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:89.00 stddev:7.48 (8.4%) stddev-sans-first:0.73 09:05:16 INFO - PID 1665 | Values: 113.0 89.0 90.0 89.0 89.0 89.0 89.0 89.0 91.0 90.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:97.00 stddev:17.53 (18.1%) stddev-sans-first:18.40 09:05:16 INFO - PID 1665 | Values: 97.0 98.0 116.0 151.0 96.0 95.0 98.0 97.0 97.0 97.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:90.00 stddev:21.00 (23.3%) stddev-sans-first:22.02 09:05:16 INFO - PID 1665 | Values: 94.0 90.0 90.0 120.0 146.0 89.0 130.0 89.0 92.0 90.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:52.00 stddev:1.97 (3.8%) stddev-sans-first:1.94 09:05:16 INFO - PID 1665 | Values: 49.0 49.0 49.0 52.0 49.0 52.0 53.0 54.0 53.0 51.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.50 stddev:2.39 (4.3%) stddev-sans-first:2.00 09:05:16 INFO - PID 1665 | Values: 51.0 57.0 53.0 56.0 58.0 59.0 54.0 54.0 55.0 55.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:80.50 stddev:3.44 (4.3%) stddev-sans-first:3.60 09:05:16 INFO - PID 1665 | Values: 81.0 79.0 79.0 82.0 82.0 77.0 83.0 80.0 71.0 80.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:153.00 stddev:15.92 (10.4%) stddev-sans-first:15.33 09:05:16 INFO - PID 1665 | Values: 133.0 188.0 147.0 144.0 152.0 150.0 132.0 160.0 154.0 160.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.30 Median:179.00 stddev:12.89 (7.2%) stddev-sans-first:13.58 09:05:16 INFO - PID 1665 | Values: 179.0 175.0 179.0 178.0 180.0 176.0 207.0 208.0 177.0 174.0 09:05:16 INFO - PID 1665 | 09:05:16 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:67.00 stddev:6.85 (10.2%) stddev-sans-first:7.25 09:05:16 INFO - PID 1665 | Values: 67.0 64.0 63.0 67.0 68.0 65.0 66.0 66.0 67.0 87.0 09:05:16 INFO - PID 1665 | -------- Summary: end -------- 09:05:16 INFO - PID 1665 | 09:05:17 INFO - TEST-INFO | 1665: exit 0 09:05:17 INFO - TEST-OK | kraken | took 89540ms 09:05:17 INFO - SUITE-END | took 1235s 09:05:18 INFO - Completed test suite (00:20:35) 09:05:18 INFO - javascript benchmark 09:05:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8417.167808171223, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3088.4286407014706, 3546.219831637039, 3624.2389098289373, 3664.1389273520717, 3694.833732482038, 5555.3611334696525, 6259.593126446778, 6305.2433707955, 6334.271558770743, 6339.27098383686, 6361.268454127777, 6377.13908622337, 6399.976003839387, 6409.935900640992, 6419.069234960928, 4792.760361981905, 5552.500274975261, 5602.915956260659, 5638.210650508932, 5638.661680299195, 5447.0, 5525.723713814293, 5613.326400831906, 5623.7469313880765, 5628.409017053192, 5493.972530137364, 5524.447555244484, 5531.806386776443, 5551.0, 5579.8884022319735, 5585.273914391144, 5595.160725891108, 5596.832095037155, 5614.29821272341, 5643.351014633328, 4871.634627402959, 5307.548858347045, 5482.424345443711, 5495.422980587061, 5589.916151257735, 6315.24217093947, 6320.93679063208, 6325.9878419453125, 6326.715297811564, 6334.081558174039, 6607.3392660734025, 6664.266930637626, 6879.415249703781, 6911.0, 6912.0, 5338.306020217386, 6828.351306625863, 6943.1407401490005, 6956.373926346653, 6998.650067496554, 5739.512141467939, 6063.605370764749, 6498.0, 6517.478601711851, 6530.249021362509, 6882.965585172042, 6958.165020197609, 6968.0, 6969.16370035599, 6980.616066116311, 6713.462922966151, 6803.251642319341, 6833.0, 6836.794896153124, 6841.589504629638, 6962.895556566555, 6984.615846128511, 6988.930110698828, 6999.51003429755, 7034.40207582351, 6839.521233513706, 6886.24251332353, 6906.0, 6908.723651053901, 6913.1358580177475, 6648.767293144813, 6727.327267273234, 6739.224989126215, 6805.251422343538, 6916.792496225121, 4719.315699223662, 5414.945850541523, 5438.184272359178, 5465.699386533779, 5518.503334699816, 4074.4703188585886, 4860.95139048605, 4927.7536123193695, 4931.654784165074, 4948.480409556944, 6408.03879418091, 6414.102025716404, 6438.839029024218, 6442.6778661066755, 6456.612603243821, 6949.7567585134275, 6984.126984126984, 7006.159260888726, 7017.649117544102, 7023.33278338557, 6180.752769889244, 6312.684365781693, 6323.336049714806, 6378.649174295366, 6400.55196136266, 6127.678242056309, 6137.693115344215, 6142.815715528542, 6143.696113957266, 6200.875982480416, 7169.53398029116, 7177.031100801325, 7194.96402518005, 7198.064251647253, 7202.387797037101, 6985.1967023790885, 7013.228544860163, 7028.6485675714985, 7040.929590704028, 7062.682179302046, 6884.862302754017, 7136.607486588287, 7170.89243661356, 7171.928280717126, 7190.892136618059, 6699.29657385987, 6732.124823772878, 6732.798016059526, 6747.460203183831, 6836.042953986545, 5878.29460464747, 6966.1988871280455, 7009.369156775914, 7042.43660507148, 7043.718251269892, 6772.356626120511, 6781.593104413553, 6783.4573234142135, 6805.217399998865, 6816.693248803716, 6622.668866556556, 6631.071649969104, 6645.966770166118, 6802.14973128359, 6805.727770889307, 6291.559590828598, 6399.520035997226, 6414.166158399565, 6416.967915160394, 6483.837904052436, 6728.529002969745, 6774.8645027100165, 6786.185657721105, 6791.966040169767, 6807.387335139663, 6412.166418365583, 6566.967165164335, 6595.472362211108, 6617.205935287604, 6627.668616569056], "value": 6246.9010256789215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.5645707774878, 842.473454091193, 844.7127976487994, 861.9310455163587, 877.3726785348474, 37565.81217093914, 38161.23677526448, 38233.42649860247, 38260.80869595648, 38344.42483362748, 37489.250214995685, 37600.623993760055, 37641.435378469345, 37722.245555088884, 37813.432798508045, 7369.4841361104745, 7548.5848278344665, 7552.735654252102, 7562.62186890656, 7586.848263034736, 7424.220456852034, 7501.5874126923, 7521.473496855222, 7537.623118844036, 7544.622768861563, 7507.887381689279, 7508.69965201394, 7514.361279291267, 7518.323350898418, 7529.32236098751, 7479.813004674872, 7995.0, 8314.293285070717, 8330.250277475056, 8339.20777526134, 8153.510789352657, 8231.835363292701, 8236.094029656733, 8250.46371985819, 8252.958735206286, 36258.456123158176, 36563.451548226796, 36570.0, 36579.0, 36656.633433665585, 3550.1834578046937, 3600.981995090034, 3607.134287770942, 3607.512985746917, 3615.2769446110883, 29669.554956675453, 29740.0, 29848.0, 29866.402671946656, 29879.402411951858, 3024.894128705507, 3105.0374383940825, 3135.451296023187, 3140.0893740815136, 3146.5594816725675, 261098.0, 264549.0, 264962.0, 330631.0, 333446.0, 38388.04029899219, 46947.765261173474, 47019.5298047022, 47137.0, 47468.5253147471, 51117.74441127771, 51163.0, 51188.232176517384, 51227.0, 51263.0, 15357.078575285519, 15425.228738563026, 15515.84484155144, 15522.767158492632, 15528.922355388377, 12651.493940242288, 12696.0, 12735.0, 12799.552015679592, 12820.0, 13784.0, 13801.792973105412, 13807.17156970585, 13828.446862125402, 13910.791338129937, 6143.877122457526, 6178.135061091451, 6207.720652570645, 6209.19280493542, 6212.782552610637, 961.5769061612868, 979.2019504104132, 982.29274922056, 1011.585250047477, 1013.4628646817199, 19730.0, 20578.38264852057, 22469.648546615794, 22913.427164321027, 23146.0, 1004.2417974429405, 1011.3881101933224, 1039.8908114648004, 1072.8068947589509, 1075.9193060520493, 11558.364289963965, 11612.361320127306, 11726.648200553997, 11769.411529423494, 12025.037996960222, 360933.0, 363328.0, 363406.0, 363776.0, 363833.0, 53735.46264537383, 54156.0, 54188.645283868216, 54275.72862135664, 54316.0, 1432.978505322421, 1470.0811992504684, 1524.542637208855, 1533.6089297229357, 1558.6259297768454, 21905.411329209117, 22161.55676886421, 22466.438339041655, 22676.88661556682, 23882.641760373957, 38030.619693802706, 38256.0, 38415.84752457431, 38424.80787596044, 38469.03827404337, 287713.0, 287843.0, 288234.0, 289051.0, 289538.0, 28461.861525538745, 29239.83040678349, 29273.414531709674, 32379.51430728588, 35239.29521409506, 35029.0, 41774.1645167101, 41989.740307789594, 43538.0, 43628.69113926587, 24807.62788558137, 27800.165995020183, 28357.0, 29070.418591628473, 30463.93376231821, 29033.709662903944, 29713.702862971957, 30469.847650761603, 30510.237244069074, 30613.846930765205, 32670.509942350407, 36593.26813463769, 36622.633773661924, 36657.26685466329, 36743.26513469769, 26545.7345426551, 27107.1867843965, 30237.0, 30308.0, 31380.37239255248, 23267.418314542272, 24273.51452970966, 24308.87845560761, 24837.87581062083, 24906.50186996286], "value": 17120.75039278779, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.9592034677053, 521.9164933610621, 527.174971170119, 538.3701069748394, 549.6125231711644, 17367.565810854736, 21833.781662183374, 22230.333090007327, 22758.886205568968, 22991.310260692186, 23808.166714165007, 25516.617250741252, 25614.743852561467, 25658.973641054334, 25667.0, 6541.182352205962, 6693.096431981693, 6697.062411262427, 6715.496337774662, 6726.562773419737, 6425.742970281207, 6599.0, 6634.104395906539, 6639.568428052161, 6676.666166691671, 6186.752529898821, 6485.8054258372085, 6507.707153178095, 6539.901901471458, 6555.147830782015, 6524.510661700344, 6526.249481309639, 6548.639824809634, 6549.0, 6554.868902621968, 6035.517158627299, 6038.426349496791, 6044.486218671374, 6053.818385448443, 6064.06007068905, 28086.2978425541, 30377.392452151053, 30635.540466893013, 30703.385932281228, 30846.22884427885, 2302.3323236261463, 2317.2932255662017, 2335.311083230451, 2343.5195784864063, 2348.5068135691527, 19071.237150513964, 19557.902210489, 19558.92425916574, 19678.606427871364, 19694.70457943132, 2158.9892050539647, 2200.8679479231296, 2231.308294428716, 2240.361496973355, 2245.978079973608, 199459.002704987, 200921.0, 203084.98457507766, 204719.0, 204946.0, 15691.764623530657, 15728.213589320718, 15848.286827092808, 15860.969037012557, 16068.196590170444, 20892.791072089083, 20941.952902355126, 21018.89490552568, 21022.894885525475, 21034.579308413748, 10492.0, 10587.4706264688, 10607.098396636373, 10613.097886679565, 10614.787704245871, 10040.397576145455, 10044.096031357212, 10073.345232559861, 10083.142932850644, 10144.746381340525, 8922.420042697204, 9254.12085851843, 9314.0, 9390.483523406278, 9399.201067909167, 2596.7143614202423, 2602.2063270702615, 2614.9215523534326, 2627.4876399102372, 2742.506348857185, 842.6334544473174, 913.7898283394857, 945.7206142206434, 1008.8297574813181, 1021.4994652620163, 18230.63538729218, 18245.452636420927, 19140.80859191418, 22215.22246721357, 22910.885445572665, 987.9555420006117, 1000.6797824696027, 1052.7947050325113, 1056.1761825775907, 1056.3872953686844, 9263.073692630818, 9510.76360073186, 11222.887771122185, 11311.321320720783, 11815.763684726257, 564265.0, 580345.0982745059, 595876.0, 604561.9543804594, 606619.9669001626, 142227.0, 163888.0, 164148.0, 166695.0, 166821.0, 1146.599573626277, 1189.2448295332354, 1200.3158199826016, 1200.4897918384652, 1210.3706072842074, 19223.0, 19385.127669254634, 20244.987750612705, 20293.391198264075, 20560.794392056487, 70554.29445705477, 71530.28469715237, 71764.64117679378, 72434.27565724275, 74417.0, 69498.0, 93373.0, 94544.52727736592, 96565.5171724165, 99019.0, 11944.283342999448, 12021.699457513283, 12176.147669663038, 12196.573119941115, 12207.206531575774, 13539.661508461973, 16291.918540407221, 18588.349407770664, 18635.161417735962, 18815.435536933914, 14724.337404816595, 14888.478903238334, 14947.476838310607, 14997.77503337429, 15166.924165379103, 12298.631041068782, 12502.0, 13146.34268286601, 13556.661083472993, 13840.4463821446, 15064.246787660353, 15456.459023934109, 15525.146116963675, 15586.610334741723, 15698.0, 11695.88304116948, 11708.648740537457, 11735.41322933867, 11905.583304584297, 11946.581869634521, 14225.14649121056, 14829.96190266671, 14927.477538286108, 14933.328000239799, 15067.09597424158], "value": 11929.647974173531, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.4745228182587, 1459.1536908593016, 1461.0576178364954, 1469.5958611381873, 1475.9778603320945, 13657.931710341447, 14199.935004874636, 14218.146911185337, 14220.288985550707, 14244.216568088765, 13582.524611638595, 13952.2326272055, 13962.720745585082, 13970.16179029259, 13975.580732578013, 2511.573032584456, 2751.857978938738, 2790.7767378609715, 2815.5635876439183, 2833.150054983507, 2356.4815740537056, 2797.216779301799, 2797.342624483255, 2801.845898475583, 2802.4675311690817, 2649.986750066247, 2768.709285525021, 2850.714928507153, 2856.214541001231, 2856.971430285702, 2814.085422237781, 2849.7292757188034, 2850.814697044696, 2854.7145285471493, 2861.298981749464, 2808.8033837631374, 2830.249983754307, 2831.6318878545862, 2835.4470878178763, 2851.543752999531, 13443.798343024764, 13682.657933551642, 13702.45190192391, 13726.65683357914, 13734.725305493834, 7609.124950630693, 7910.0, 7917.881231781528, 7926.0884998225365, 7933.603319833986, 4609.723416595015, 4849.781759820817, 4936.703797772109, 4946.5795407390415, 4955.959248557807, 1419.8935079868947, 1422.5518961527087, 1422.6230049037013, 1446.8263808343065, 1448.5509492057497, 17762.733558996624, 21067.0, 21081.262155824472, 21189.0, 21243.0, 6628.701708423133, 6783.796486105425, 6858.279880612564, 6901.965490172517, 6920.861582768417, 14424.206668633116, 14443.422263109567, 14448.422063117569, 14457.92771036138, 14497.202653853928, 7586.0, 7691.730789422343, 7695.6152192390155, 7697.191794861459, 7774.1837107104075, 6751.345920249472, 6997.615131167835, 7051.964740176266, 7112.466565007541, 7123.287671232939, 5228.928069745693, 5288.81489147878, 5298.867528311823, 5349.117395629755, 5377.5429088527135, 1508.6077619818707, 1529.3653133949285, 1532.4559781277485, 1542.7840102385676, 1561.8594326510668, 608.2640005593282, 750.4821673045581, 790.0319426495263, 849.426637020009, 851.4082712514867, 7957.403194760301, 8025.077116131602, 8038.236367545191, 8380.120087390858, 8410.453320534145, 954.9713508594614, 959.5010594490826, 964.252704154286, 1001.9448930308757, 1029.711680729397, 4819.0, 4912.410510738734, 5037.29477873098, 5074.974625126924, 5235.921461178085, 18268.086595670164, 18586.721199182022, 18838.529036773914, 18857.339993100177, 18870.056497175086, 18072.819271807377, 18175.636487270443, 18321.81678183201, 18511.81488185101, 18687.43937681872, 9576.0, 9713.320067595201, 9782.85325720129, 9802.95098524517, 9824.705258842247, 7489.438292128003, 7527.510711803715, 8159.714409995621, 8211.3430925527, 8229.95885020571, 13707.314634268447, 16084.35662573484, 16316.42892498757, 16804.075775832447, 16824.74762878533, 16992.830071699125, 17676.82323176803, 17695.115244237993, 17743.0, 17763.0, 10804.945975270388, 10909.890901090888, 10964.396958167377, 11032.245183188772, 11119.388433636228, 12482.812757808822, 12615.0, 12615.495380185057, 12634.620961371174, 12669.366531673564, 9905.702828915144, 10100.040496152853, 10143.391396516232, 10165.695029148841, 10263.178945684178, 9745.415275083518, 9795.755106122118, 9856.5564549594, 10161.745956351151, 10340.37957722539, 9347.345685801929, 9374.718758437259, 9460.526973651427, 9488.240940724587, 9509.952450237704, 6612.867742645024, 6749.0888730020815, 6792.1849378072975, 6816.897746533905, 7112.324329188718, 5854.385289544707, 6922.550034247758, 7250.274972502708, 7318.1949985502615, 7334.083239595051], "value": 6249.0648817698275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.97051885028003, 426.51164416742847, 445.68356466908494, 463.5758281172727, 474.0187811230749, 5871.383504732005, 6352.428281454668, 6471.482281417487, 6474.190726159236, 6493.707783149758, 4951.3563236779255, 5490.298007617641, 5930.140129681194, 6024.48791852693, 6343.175387199658, 4563.977180114103, 4713.892235324696, 4735.384400027991, 4741.075490279397, 4761.809527618909, 4152.584741525853, 4359.280718681413, 4703.529647035303, 4734.9763251183695, 4740.549647783455, 3767.849286028569, 4711.246200607903, 4722.291656251573, 4735.73953432561, 4820.0, 3960.3663413853646, 4462.509123996374, 4531.56950089741, 4718.834840780591, 4781.613332133677, 3343.3981883261004, 3480.7911525308564, 4135.97932010338, 4145.937810932838, 4151.543330233702, 5407.459254074601, 5419.756110975016, 6110.908336374958, 6190.257169139687, 6239.0, 5183.922241166386, 5342.759575819058, 5637.746301416405, 5641.435856414367, 5688.146777983295, 5634.830955071355, 6183.0, 6737.528373013891, 6797.694103765342, 6818.795436136924, 4754.310624959361, 4756.833510827105, 4773.856784296477, 4776.331313616097, 4781.258904869716, 6219.657918814463, 6678.597494526155, 7061.0, 7138.430784607696, 7242.98598196253, 4495.527969563149, 4636.698614590108, 4779.95220047802, 4855.465898751135, 4884.8290309838985, 4363.734516990101, 4937.35814344133, 4940.060663904971, 4953.6037117030555, 4958.603311735053, 5086.0, 5291.867703307448, 5609.635373700697, 5627.831165064973, 5657.773689052474, 5819.854503637443, 6816.727330906808, 6819.624920629314, 6893.0, 6936.722531098801, 1529.1895295493407, 1685.9072750998569, 1700.4643537285715, 1700.6428649983395, 1708.128854284299, 1033.7415646088477, 1107.8423047914948, 1133.7845809296207, 1139.1114930354338, 1149.6263714292888, 4986.376702912136, 6347.0479428086155, 6365.2679941806355, 6516.902246466307, 6578.76974305897, 5959.433853783884, 6594.670266486656, 6898.206706228848, 6913.3777959983845, 7072.787816365517, 5193.636445448858, 5734.369219385864, 6316.199883033313, 6447.259914536397, 6453.903191452132, 3213.750374737693, 3294.5058241263523, 3295.1432627516538, 3368.5789276340456, 3687.3547129252274, 5743.511801496836, 7241.637918104074, 7262.491625586264, 7310.4151667866445, 7341.119065712149, 6310.643211709505, 6911.9308806912295, 7028.894566581505, 7200.963995179991, 7297.525660832135, 5732.0, 5735.885282294247, 6029.8191054268445, 7093.0424392707355, 7242.311980361963, 7055.3650171484815, 7061.540999834994, 7081.221065682874, 7086.007958885657, 7101.609411482418, 4064.8577299795547, 6921.134858142732, 6966.477514186355, 6978.651067446709, 6979.965100174467, 6225.817094751983, 6752.818256805138, 7160.283971602798, 7231.674574644258, 7238.647439582051, 5105.0, 5401.771039813414, 5429.864253393696, 5434.10337294342, 5449.536789372789, 6746.392824645805, 7061.752838650623, 7101.786946391617, 7104.183018952886, 7126.251743566851, 6272.655003974825, 6549.541532092899, 6798.388145066968, 6815.386615204656, 6858.6227757471825, 6007.339192688713, 6228.626282423069, 6474.931636280149, 6818.727250910107, 6865.862682746417, 6691.665416729242, 6701.530892837454, 6704.66476676174, 6704.793137235344, 6720.630365329758, 6841.126726774866, 6849.691763870737, 6861.828454288683, 6865.965670171817, 6951.617661028692, 6005.849853753691, 6152.384761523812, 6664.933350666625, 6681.5656982296, 6705.597664140168], "value": 4962.826860564261, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.5504474837471, 812.3420029775885, 841.436237720727, 857.2199298638241, 863.1756672377876, 13513.797293040612, 17174.39889603864, 17210.741838872425, 17212.48362549124, 17235.052072136026, 15763.60590985226, 16472.0, 17083.974961502317, 17650.38223662172, 18813.341533046347, 993.4635296939662, 1003.282652903174, 1019.306871327497, 1026.3892983674702, 1028.88682244953, 957.0812020460367, 1013.1388319928076, 1026.379040680389, 1026.7073883943078, 1028.5731421460084, 940.1397721085199, 998.4857798233916, 1009.0072107587492, 1046.0376453662611, 1047.350642601588, 1025.9692209233735, 1032.633415137623, 1041.494874985635, 1045.890181530936, 1051.159072741811, 771.2595907928395, 779.3920741821388, 779.7193010516266, 785.9882101768478, 793.0562630469723, 16421.343146274136, 16556.0, 16556.172191390382, 16592.336306547724, 16870.915645421817, 17034.914825425916, 17722.822771772248, 17756.934583925005, 17940.641187176316, 18152.818471815244, 14897.329620167011, 14909.329080191417, 14939.25303734809, 15015.399384024626, 15053.247337633074, 3592.2456284180353, 3613.8554457821656, 3621.149029977953, 3624.5650521937273, 3644.7813131211947, 125386.37306813408, 128000.35999820035, 129123.0, 131106.0, 132521.0, 13072.738545229042, 13503.797443038362, 13727.931360343135, 14450.277486125651, 14473.78289325661, 26610.733892661214, 27090.78091485888, 27182.04862829831, 27252.454950900872, 27400.451990960468, 8028.638711258008, 8323.542205178652, 8324.417290789708, 8331.750047498585, 8401.0, 3780.5085338905765, 3827.770333779982, 3860.768353898775, 3861.0733423978604, 3867.45855580219, 4719.244920812654, 4775.331453596499, 4810.086083644096, 4817.855464336076, 4823.710577365369, 888.657866721304, 937.437537477508, 1040.70339953113, 1042.489180301662, 1051.8579991701176, 607.9331273559905, 655.3250152343094, 656.3502132888406, 675.8175292670951, 676.1818199977984, 10796.460176991119, 11475.942620287013, 11689.76620467586, 11933.761324773455, 12474.378330816935, 629.719774700254, 643.7585905285518, 653.4641593892962, 680.2415306932735, 680.7106979533678, 5573.860653483614, 5580.972095139498, 6206.317305096436, 6356.9364306357265, 6712.295209003082, 141593.0, 142623.0, 143843.2807835975, 148184.2590787039, 153166.0, 112416.0, 123973.0, 124940.0, 125047.0, 125214.3739281316, 99463.50804737935, 101763.47354789832, 104878.47560762147, 107913.0, 113742.43128784468, 12137.757244854876, 12505.874471297628, 12572.559960401342, 12621.0, 13196.14225075367, 44157.55842441534, 44254.55745442633, 44360.77819610881, 47227.76386118047, 48141.75929120472, 77767.0, 85432.0, 86199.0, 88243.11756882606, 89187.0, 17453.47639570815, 17691.73462398091, 17789.46631601054, 18385.356512521998, 18514.44456666302, 18094.0, 18879.716804247673, 19217.423477295702, 19278.421647350602, 19546.60906781885, 17771.73342399891, 17981.910090449463, 18243.635127297115, 18432.539186520444, 18466.446006619823, 17420.47738567845, 17738.467845964642, 17788.110594469967, 17920.551986200448, 18539.44381668552, 14482.927585362006, 17021.40425085116, 17142.742858857375, 18104.547386315448, 18140.546486337946, 11829.290242585472, 11956.940215298868, 12155.270683759003, 12167.878321216675, 12215.938920305342, 13707.451701931812, 14464.493742718954, 14571.34428950721, 14638.0, 14672.706545869236], "value": 8986.894948264002, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 106.0, 120.0, 118.0, 116.0, 115.0, 101.0, 116.0, 115.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 158.0, 177.0, 103.0, 127.0, 110.0, 146.0, 150.0, 113.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 201.0, 218.0, 279.0, 208.0, 193.0, 200.0, 204.0, 198.0], "value": 209.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 79.0, 80.0, 158.0, 81.0, 84.0, 84.0, 75.0, 122.0, 57.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 119.0, 63.0, 95.0, 111.0, 135.0, 98.0, 71.0, 107.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 89.0, 90.0, 89.0, 89.0, 89.0, 89.0, 89.0, 91.0, 90.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 116.0, 151.0, 96.0, 95.0, 98.0, 97.0, 97.0, 97.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 90.0, 90.0, 120.0, 146.0, 89.0, 130.0, 89.0, 92.0, 90.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 52.0, 49.0, 52.0, 53.0, 54.0, 53.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 57.0, 53.0, 56.0, 58.0, 59.0, 54.0, 54.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 79.0, 82.0, 82.0, 77.0, 83.0, 80.0, 71.0, 80.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 188.0, 147.0, 144.0, 152.0, 150.0, 132.0, 160.0, 154.0, 160.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 175.0, 179.0, 178.0, 180.0, 176.0, 207.0, 208.0, 177.0, 174.0], "value": 183.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 67.0, 68.0, 65.0, 66.0, 66.0, 67.0, 87.0], "value": 68.0, "unit": "score"}]}]} 09:05:18 INFO - Return code: 0 09:05:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:18 INFO - # TBPL SUCCESS # 09:05:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:05:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:05:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:05:18 INFO - 2017-06-16 09:05:18.267 screenresolution[1673:11842] starting screenresolution argv=screenresolution get 09:05:18 INFO - 2017-06-16 09:05:18.270 screenresolution[1673:11842] Display 0: 1600x1200x32@60 09:05:18 INFO - 2017-06-16 09:05:18.276 screenresolution[1674:11845] starting screenresolution argv=screenresolution list 09:05:18 INFO - Available Modes on Display 0 09:05:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:05:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:05:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:05:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:05:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:05:18 INFO - Intel Iris: 09:05:18 INFO - Chipset Model: Intel Iris 09:05:18 INFO - Type: GPU 09:05:18 INFO - Bus: Built-In 09:05:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:05:18 INFO - Vendor: Intel (0x8086) 09:05:18 INFO - Device ID: 0x0a2e 09:05:18 INFO - Revision ID: 0x0009 09:05:18 INFO - Displays: 09:05:18 INFO - Display: 09:05:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:05:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:05:18 INFO - Display Serial Number: 1600x1200 60 09:05:18 INFO - Main Display: Yes 09:05:18 INFO - Mirror: Off 09:05:18 INFO - Online: Yes 09:05:18 INFO - Rotation: Supported 09:05:18 INFO - Return code: 0 09:05:18 INFO - Running post-action listener: _resource_record_post_action 09:05:18 INFO - [mozharness: 2017-06-16 16:05:18.511171Z] Finished run-tests step (success) 09:05:18 INFO - Running post-run listener: _resource_record_post_run 09:05:18 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 35265536; Write bytes: 560758272; Read time: 361; Write time: 1947 09:05:18 INFO - TinderboxPrint: CPU usage
53.1% 09:05:18 INFO - TinderboxPrint: I/O read bytes / time
35,265,536 / 361 09:05:18 INFO - TinderboxPrint: I/O write bytes / time
560,758,272 / 1,947 09:05:18 INFO - TinderboxPrint: CPU idle
2,357.9 (46.9%) 09:05:18 INFO - TinderboxPrint: CPU system
1,012.7 (20.1%) 09:05:18 INFO - TinderboxPrint: CPU user
1,659.1 (33.0%) 09:05:18 INFO - TinderboxPrint: Swap in / out
274,907,136 / 0 09:05:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232100352; Write bytes: 227530240; Read time: 16808; Write time: 476 09:05:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:05:18 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 33254400; Write bytes: 313915392; Read time: 336; Write time: 1424 09:05:19 INFO - Running post-run listener: _upload_blobber_files 09:05:19 INFO - Blob upload gear active. 09:05:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:19 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 09:05:19 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'] 09:05:19 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 09:05:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:20 INFO - (blobuploader) - INFO - Done attempting. 09:05:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:05:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:20 INFO - (blobuploader) - INFO - Done attempting. 09:05:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:05:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:05:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:22 INFO - (blobuploader) - INFO - Done attempting. 09:05:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:24 INFO - (blobuploader) - INFO - Done attempting. 09:05:24 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:24 INFO - Return code: 0 09:05:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09a7b230d847e5daca5df64e52f37090f9a9d17e71a1b644f9256600c93f94829ee3c0ca579c46fa8189b57d8fe6692a9f61a1be296e9d3ccad0947fed24912d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e33e2eab4c22a5c85abb609ef4fbf8b724da6d4821128c24f376d7ff1261bd4924f22071a6cde56b21479444362394a252f8fe10401af56bc9dfc614600901a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19218c1fae5fa8c291881dc11f8b00fc88b240f4d75c5507bfc3359df6a822d9664885dc59592e4e57032ef3e3cabaf3d00a35382d365b703da3f1c9a664fdfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22919d24f66732e6c6b319a334c496d28ca65039f724d01835d31a9bf7d5ee78c4e5a5d9c0ec748216355a8134a16418b0d653224c66cf3bf7aff6701590b37f"} 09:05:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:24 INFO - Contents: 09:05:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09a7b230d847e5daca5df64e52f37090f9a9d17e71a1b644f9256600c93f94829ee3c0ca579c46fa8189b57d8fe6692a9f61a1be296e9d3ccad0947fed24912d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e33e2eab4c22a5c85abb609ef4fbf8b724da6d4821128c24f376d7ff1261bd4924f22071a6cde56b21479444362394a252f8fe10401af56bc9dfc614600901a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19218c1fae5fa8c291881dc11f8b00fc88b240f4d75c5507bfc3359df6a822d9664885dc59592e4e57032ef3e3cabaf3d00a35382d365b703da3f1c9a664fdfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22919d24f66732e6c6b319a334c496d28ca65039f724d01835d31a9bf7d5ee78c4e5a5d9c0ec748216355a8134a16418b0d653224c66cf3bf7aff6701590b37f"} 09:05:24 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:24 INFO - Copying logs to upload dir... 09:05:24 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.015795 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-16 09:05:24.286382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-16 09:05:24.290137) ========= 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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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/09a7b230d847e5daca5df64e52f37090f9a9d17e71a1b644f9256600c93f94829ee3c0ca579c46fa8189b57d8fe6692a9f61a1be296e9d3ccad0947fed24912d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e33e2eab4c22a5c85abb609ef4fbf8b724da6d4821128c24f376d7ff1261bd4924f22071a6cde56b21479444362394a252f8fe10401af56bc9dfc614600901a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19218c1fae5fa8c291881dc11f8b00fc88b240f4d75c5507bfc3359df6a822d9664885dc59592e4e57032ef3e3cabaf3d00a35382d365b703da3f1c9a664fdfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22919d24f66732e6c6b319a334c496d28ca65039f724d01835d31a9bf7d5ee78c4e5a5d9c0ec748216355a8134a16418b0d653224c66cf3bf7aff6701590b37f"} build_url:https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010846 build_url: 'https://queue.taskcluster.net/v1/task/YNJe5dEPSbWzMiFeNk4Wtg/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/09a7b230d847e5daca5df64e52f37090f9a9d17e71a1b644f9256600c93f94829ee3c0ca579c46fa8189b57d8fe6692a9f61a1be296e9d3ccad0947fed24912d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e33e2eab4c22a5c85abb609ef4fbf8b724da6d4821128c24f376d7ff1261bd4924f22071a6cde56b21479444362394a252f8fe10401af56bc9dfc614600901a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19218c1fae5fa8c291881dc11f8b00fc88b240f4d75c5507bfc3359df6a822d9664885dc59592e4e57032ef3e3cabaf3d00a35382d365b703da3f1c9a664fdfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/22919d24f66732e6c6b319a334c496d28ca65039f724d01835d31a9bf7d5ee78c4e5a5d9c0ec748216355a8134a16418b0d653224c66cf3bf7aff6701590b37f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-16 09:05:24.323121) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 09:05:24.323503) ========= 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.SvPJfzVrrv/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.6eR6C2gOhm/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.004578 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 09:05:24.372544) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-16 09:05:24.372880) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-16 09:05:24.373215) ========= ========= Total master_lag: 0.18 =========