builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0325 starttime: 1497496802.02 results: success (0) buildid: 20170614193133 builduid: 82a3124701e443a2922fae95ae165188 revision: f5b4c1e362ec5c88ff5b08535c8277278c6a6713 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.018261) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.018711) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.019314) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.091927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.092447) ========= 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.BpbKAeeUu3/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.INP0DctADJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-14 20:20:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-06-14 20:20:02 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143425 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.258205) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.258511) ========= 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.BpbKAeeUu3/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.INP0DctADJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.027266 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:02.306711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 20:20:02.307040) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f5b4c1e362ec5c88ff5b08535c8277278c6a6713 --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 f5b4c1e362ec5c88ff5b08535c8277278c6a6713 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BpbKAeeUu3/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.INP0DctADJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-14 20:20:02,408 truncating revision to first 12 chars 2017-06-14 20:20:02,408 Setting DEBUG logging. 2017-06-14 20:20:02,408 attempt 1/10 2017-06-14 20:20:02,408 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f5b4c1e362ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 20:20:03,345 unpacking tar archive at: mozilla-beta-f5b4c1e362ec/testing/mozharness/ program finished with exit code 0 elapsedTime=1.209371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 20:20:03.538064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:03.538392) ========= script_repo_revision: f5b4c1e362ec5c88ff5b08535c8277278c6a6713 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:03.538765) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:03.539054) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 20:20:03.557121) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-14 20:20:03.557531) ========= /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.BpbKAeeUu3/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.INP0DctADJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:20:03 INFO - MultiFileLogger online at 20170614 20:20:03 in /builds/slave/test 20:20:03 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 20:20:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:20:03 INFO - {'append_to_log': False, 20:20:03 INFO - 'base_work_dir': '/builds/slave/test', 20:20:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:20:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:20:03 INFO - 'branch': 'Mozilla-Beta', 20:20:03 INFO - 'buildbot_json_path': 'buildprops.json', 20:20:03 INFO - 'config_files': ('talos/mac_config.py',), 20:20:03 INFO - 'default_actions': ('clobber', 20:20:03 INFO - 'read-buildbot-config', 20:20:03 INFO - 'download-and-extract', 20:20:03 INFO - 'populate-webroot', 20:20:03 INFO - 'create-virtualenv', 20:20:03 INFO - 'install', 20:20:03 INFO - 'setup-mitmproxy', 20:20:03 INFO - 'run-tests'), 20:20:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:20:03 INFO - 'download_minidump_stackwalk': True, 20:20:03 INFO - 'download_symbols': 'ondemand', 20:20:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:20:03 INFO - 'tooltool.py': '/tools/tooltool.py', 20:20:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:20:03 INFO - '/tools/misc-python/virtualenv.py')}, 20:20:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:20:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:20:03 INFO - 'gecko_profile': False, 20:20:03 INFO - 'gecko_profile_interval': 0, 20:20:03 INFO - 'installer_path': 'installer.exe', 20:20:03 INFO - 'log_level': 'info', 20:20:03 INFO - 'log_name': 'talos', 20:20:03 INFO - 'log_to_console': True, 20:20:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:20:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:20:03 INFO - 'opt_config_files': (), 20:20:03 INFO - 'pip_index': False, 20:20:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:20:03 INFO - 'cmd': ('bash', 20:20:03 INFO - '-c', 20:20:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:20:03 INFO - 'enabled': True, 20:20:03 INFO - 'halt_on_failure': False, 20:20:03 INFO - 'name': 'check_screen_resolution'},), 20:20:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:20:03 INFO - 'cmd': ('bash', 20:20:03 INFO - '-c', 20:20:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:20:03 INFO - 'enabled': True, 20:20:03 INFO - 'halt_on_failure': False, 20:20:03 INFO - 'name': 'check_screen_resolution'},), 20:20:03 INFO - 'run_cmd_checks_enabled': True, 20:20:03 INFO - 'suite': 'dromaeojs-e10s', 20:20:03 INFO - 'system_bits': '32', 20:20:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:20:03 INFO - 'title': 't-yosemite-r7-0325', 20:20:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:20:03 INFO - 'use_talos_json': True, 20:20:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:20:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:20:03 INFO - 'work_dir': 'build'} 20:20:03 INFO - [mozharness: 2017-06-15 03:20:03.766709Z] Running clobber step. 20:20:03 INFO - Running pre-action listener: _resource_record_pre_action 20:20:03 INFO - Running main action method: clobber 20:20:03 INFO - rmtree: /builds/slave/test/build 20:20:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:20:06 INFO - Running post-action listener: _resource_record_post_action 20:20:06 INFO - [mozharness: 2017-06-15 03:20:06.049703Z] Finished clobber step (success) 20:20:06 INFO - [mozharness: 2017-06-15 03:20:06.049894Z] Running read-buildbot-config step. 20:20:06 INFO - Running pre-action listener: _resource_record_pre_action 20:20:06 INFO - Running main action method: read_buildbot_config 20:20:06 INFO - Using buildbot properties: 20:20:06 INFO - { 20:20:06 INFO - "project": "", 20:20:06 INFO - "product": "firefox", 20:20:06 INFO - "script_repo_revision": "production", 20:20:06 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 20:20:06 INFO - "repository": "", 20:20:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 20:20:06 INFO - "buildid": "20170614193133", 20:20:06 INFO - "pgo_build": "False", 20:20:06 INFO - "basedir": "/builds/slave/test", 20:20:06 INFO - "buildnumber": 1, 20:20:06 INFO - "slavename": "t-yosemite-r7-0325", 20:20:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:20:06 INFO - "platform": "yosemite_r7_devedition", 20:20:06 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 20:20:06 INFO - "branch": "mozilla-beta", 20:20:06 INFO - "repo_path": "releases/mozilla-beta", 20:20:06 INFO - "revision": "f5b4c1e362ec5c88ff5b08535c8277278c6a6713", 20:20:06 INFO - "stage_platform": "macosx64-devedition", 20:20:06 INFO - "builduid": "82a3124701e443a2922fae95ae165188", 20:20:06 INFO - "slavebuilddir": "test" 20:20:06 INFO - } 20:20:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 20:20:06 INFO - Running post-action listener: _resource_record_post_action 20:20:06 INFO - [mozharness: 2017-06-15 03:20:06.053327Z] Finished read-buildbot-config step (success) 20:20:06 INFO - [mozharness: 2017-06-15 03:20:06.053464Z] Running download-and-extract step. 20:20:06 INFO - Running pre-action listener: _resource_record_pre_action 20:20:06 INFO - Running main action method: download_and_extract 20:20:06 INFO - mkdir: /builds/slave/test/build/tests 20:20:06 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')]} 20:20:06 INFO - trying https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 20:20:06 INFO - Downloading https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/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 20:20:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/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 20:20:07 INFO - Downloaded 1290 bytes. 20:20:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 20:20:07 INFO - Using the following test package requirements: 20:20:07 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 20:20:07 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 20:20:07 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 20:20:07 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 20:20:07 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 20:20:07 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 20:20:07 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 20:20:07 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 20:20:07 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 20:20:07 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 20:20:07 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 20:20:07 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 20:20:07 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 20:20:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 20:20:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 20:20:07 INFO - Fetch https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 20:20:09 INFO - Content-Length response header: 59545482 20:20:09 INFO - Bytes received: 59545482 20:20:27 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 20:20:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 20:20:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 20:20:27 INFO - Fetch https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 20:20:29 INFO - Content-Length response header: 59545482 20:20:29 INFO - Bytes received: 59545482 20:20:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 20:20:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 20:20:46 INFO - Fetch https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 20:20:47 INFO - Content-Length response header: 14454473 20:20:47 INFO - Bytes received: 14454473 20:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:20:47 INFO - trying https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 20:20:47 INFO - Downloading https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 20:20:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/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 20:20:50 INFO - Downloaded 66878156 bytes. 20:20:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 20:20:50 INFO - mkdir: /builds/slave/test/properties 20:20:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:20:50 INFO - Writing to file /builds/slave/test/properties/build_url 20:20:50 INFO - Contents: 20:20:50 INFO - build_url:https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 20:20:51 INFO - Running post-action listener: _resource_record_post_action 20:20:51 INFO - Running post-action listener: set_extra_try_arguments 20:20:51 INFO - [mozharness: 2017-06-15 03:20:51.175182Z] Finished download-and-extract step (success) 20:20:51 INFO - [mozharness: 2017-06-15 03:20:51.175336Z] Running populate-webroot step. 20:20:51 INFO - Running pre-action listener: _resource_record_pre_action 20:20:51 INFO - Running main action method: populate_webroot 20:20:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:20:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:20:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:20:51 INFO - '--tppagecycles', 20:20:51 INFO - '7'], 20:20:51 INFO - 'tests': ['tsvgm']}}, 20:20:51 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:20:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:20:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:20:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:20:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:20:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['damp', 'tps']}, 20:20:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'tests': ['damp', 'tps']}, 20:20:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['dromaeo_dom']}, 20:20:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:20:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:20:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:20:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:20:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['a11yr', 20:20:51 INFO - 'ts_paint', 20:20:51 INFO - 'tpaint', 20:20:51 INFO - 'sessionrestore', 20:20:51 INFO - 'sessionrestore_no_auto_restore', 20:20:51 INFO - 'tabpaint']}, 20:20:51 INFO - 'other-e10s': {'tests': ['a11yr', 20:20:51 INFO - 'ts_paint', 20:20:51 INFO - 'tpaint', 20:20:51 INFO - 'sessionrestore', 20:20:51 INFO - 'sessionrestore_no_auto_restore', 20:20:51 INFO - 'tabpaint']}, 20:20:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:20:51 INFO - 'ts_paint', 20:20:51 INFO - 'tpaint', 20:20:51 INFO - 'sessionrestore', 20:20:51 INFO - 'sessionrestore_no_auto_restore', 20:20:51 INFO - 'tabpaint']}, 20:20:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:20:51 INFO - 'ts_paint', 20:20:51 INFO - 'tpaint', 20:20:51 INFO - 'sessionrestore', 20:20:51 INFO - 'sessionrestore_no_auto_restore', 20:20:51 INFO - 'tabpaint']}, 20:20:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['a11yr', 20:20:51 INFO - 'ts_paint', 20:20:51 INFO - 'tpaint', 20:20:51 INFO - 'sessionrestore', 20:20:51 INFO - 'sessionrestore_no_auto_restore', 20:20:51 INFO - 'tabpaint']}, 20:20:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['a11yr', 20:20:51 INFO - 'ts_paint', 20:20:51 INFO - 'tpaint', 20:20:51 INFO - 'sessionrestore', 20:20:51 INFO - 'sessionrestore_no_auto_restore', 20:20:51 INFO - 'tabpaint']}, 20:20:51 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 20:20:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 20:20:51 INFO - 'bloom_basic_ref']}, 20:20:51 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:20:51 INFO - 'talos_options': ['--mitmproxy', 20:20:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:20:51 INFO - '--firstNonBlankPaint'], 20:20:51 INFO - 'tests': ['quantum_pageload_google', 20:20:51 INFO - 'quantum_pageload_youtube', 20:20:51 INFO - 'quantum_pageload_amazon', 20:20:51 INFO - 'quantum_pageload_facebook']}, 20:20:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['tsvgx', 20:20:51 INFO - 'tsvgr_opacity', 20:20:51 INFO - 'tart', 20:20:51 INFO - 'tscrollx', 20:20:51 INFO - 'cart', 20:20:51 INFO - 'tsvg_static']}, 20:20:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:20:51 INFO - 'tsvgr_opacity', 20:20:51 INFO - 'tart', 20:20:51 INFO - 'tscrollx', 20:20:51 INFO - 'cart', 20:20:51 INFO - 'tsvg_static']}, 20:20:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'talos_options': ['--disable-e10s'], 20:20:51 INFO - 'tests': ['tp5o']}, 20:20:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:20:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'talos_options': ['--disable-e10s', 20:20:51 INFO - '--xperf_path', 20:20:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:20:51 INFO - 'tests': ['tp5n']}, 20:20:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:20:51 INFO - 'talos_options': ['--xperf_path', 20:20:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:20:51 INFO - 'tests': ['tp5n']}}, 20:20:51 INFO - 'talos.zip': {'path': '', 20:20:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:20:51 INFO - Running post-action listener: _resource_record_post_action 20:20:51 INFO - [mozharness: 2017-06-15 03:20:51.195989Z] Finished populate-webroot step (success) 20:20:51 INFO - [mozharness: 2017-06-15 03:20:51.196107Z] Running create-virtualenv step. 20:20:51 INFO - Running pre-action listener: _resource_record_pre_action 20:20:51 INFO - Running main action method: create_virtualenv 20:20:51 INFO - Creating virtualenv /builds/slave/test/build/venv 20:20:51 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 20:20:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:20:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:20:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:20:51 INFO - Using real prefix '/tools/python27' 20:20:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:20:52 INFO - Installing distribute.............................................................................................................................................................................................done. 20:20:54 INFO - Installing pip.................done. 20:20:54 INFO - Return code: 0 20:20:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:20:55 INFO - Reading from file tmpfile_stdout 20:20:55 INFO - Output received: 20:20:55 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) 20:20:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:55 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:20:55 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 20:20:55 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 20:20:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:20:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:20:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:20:55 INFO - 'HOME': '/Users/cltbld', 20:20:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:20:55 INFO - 'LOGNAME': 'cltbld', 20:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:55 INFO - 'MOZ_NO_REMOTE': '1', 20:20:55 INFO - 'NO_EM_RESTART': '1', 20:20:55 INFO - 'PAGER': '/bin/cat', 20:20:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:55 INFO - 'PWD': '/builds/slave/test', 20:20:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:20:55 INFO - 'SHELL': '/bin/bash', 20:20:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:20:55 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:20:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:20:55 INFO - 'USER': 'cltbld', 20:20:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:20:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:55 INFO - 'XPC_FLAGS': '0x0', 20:20:55 INFO - 'XPC_SERVICE_NAME': '0', 20:20:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:55 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 20:20:55 INFO - Cleaning up... 20:20:55 INFO - Return code: 0 20:20:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:55 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:20:55 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 20:20:55 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 20:20:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:20:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:20:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:20:55 INFO - 'HOME': '/Users/cltbld', 20:20:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:20:55 INFO - 'LOGNAME': 'cltbld', 20:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:55 INFO - 'MOZ_NO_REMOTE': '1', 20:20:55 INFO - 'NO_EM_RESTART': '1', 20:20:55 INFO - 'PAGER': '/bin/cat', 20:20:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:55 INFO - 'PWD': '/builds/slave/test', 20:20:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:20:55 INFO - 'SHELL': '/bin/bash', 20:20:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:20:55 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:20:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:20:55 INFO - 'USER': 'cltbld', 20:20:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:20:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:55 INFO - 'XPC_FLAGS': '0x0', 20:20:55 INFO - 'XPC_SERVICE_NAME': '0', 20:20:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:55 INFO - Downloading/unpacking psutil>=3.1.1 20:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:20:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:20:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:20:57 INFO - Installing collected packages: psutil 20:20:57 INFO - Running setup.py install for psutil 20:20:58 INFO - building 'psutil._psutil_osx' extension 20:20:58 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 20:20:58 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 20:20:58 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 20:20:58 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 20:20:58 INFO - building 'psutil._psutil_posix' extension 20:20:58 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 20:20:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:20:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:20:58 INFO - ^ 20:20:58 INFO - 1 warning generated. 20:20:58 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 20:20:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:20:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:20:59 INFO - Successfully installed psutil 20:20:59 INFO - Cleaning up... 20:20:59 INFO - Return code: 0 20:20:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:20:59 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')]} 20:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:59 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')]} 20:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:59 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:20:59 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 20:20:59 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 20:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:20:59 INFO - 'HOME': '/Users/cltbld', 20:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:20:59 INFO - 'LOGNAME': 'cltbld', 20:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:59 INFO - 'MOZ_NO_REMOTE': '1', 20:20:59 INFO - 'NO_EM_RESTART': '1', 20:20:59 INFO - 'PAGER': '/bin/cat', 20:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:59 INFO - 'PWD': '/builds/slave/test', 20:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:20:59 INFO - 'SHELL': '/bin/bash', 20:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:20:59 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:20:59 INFO - 'USER': 'cltbld', 20:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:59 INFO - 'XPC_FLAGS': '0x0', 20:20:59 INFO - 'XPC_SERVICE_NAME': '0', 20:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:20:59 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 20:20:59 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 20:20:59 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 20:20:59 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 20:21:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:21:00 INFO - Installing collected packages: mozsystemmonitor 20:21:00 INFO - Running setup.py install for mozsystemmonitor 20:21:01 INFO - Successfully installed mozsystemmonitor 20:21:01 INFO - Cleaning up... 20:21:01 INFO - Return code: 0 20:21:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:21: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')]} 20:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21: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')]} 20:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21: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', '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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:21: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:21: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 jsonschema==2.5.1 20:21:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:01 INFO - 'HOME': '/Users/cltbld', 20:21:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:01 INFO - 'LOGNAME': 'cltbld', 20:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:01 INFO - 'MOZ_NO_REMOTE': '1', 20:21:01 INFO - 'NO_EM_RESTART': '1', 20:21:01 INFO - 'PAGER': '/bin/cat', 20:21:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:01 INFO - 'PWD': '/builds/slave/test', 20:21:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:01 INFO - 'SHELL': '/bin/bash', 20:21:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:01 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:01 INFO - 'USER': 'cltbld', 20:21:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:01 INFO - 'XPC_FLAGS': '0x0', 20:21:01 INFO - 'XPC_SERVICE_NAME': '0', 20:21:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:01 INFO - Downloading/unpacking jsonschema==2.5.1 20:21: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 20:21: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 20:21: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 20:21: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 20:21:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:21:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:03 INFO - Downloading functools32-3.2.3-2.tar.gz 20:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:21:03 INFO - warning: no files found matching '*.txt' 20:21:03 INFO - no previously-included directories found matching 'build' 20:21:03 INFO - no previously-included directories found matching 'dist' 20:21:03 INFO - no previously-included directories found matching '.git*' 20:21:03 INFO - Installing collected packages: jsonschema, functools32 20:21:03 INFO - Running setup.py install for functools32 20:21:03 INFO - warning: no files found matching '*.txt' 20:21:03 INFO - no previously-included directories found matching 'build' 20:21:03 INFO - no previously-included directories found matching 'dist' 20:21:03 INFO - no previously-included directories found matching '.git*' 20:21:03 INFO - Successfully installed jsonschema functools32 20:21:03 INFO - Cleaning up... 20:21:03 INFO - Return code: 0 20:21:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:03 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:21:03 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 20:21:03 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 20:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:03 INFO - 'HOME': '/Users/cltbld', 20:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:03 INFO - 'LOGNAME': 'cltbld', 20:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:03 INFO - 'MOZ_NO_REMOTE': '1', 20:21:03 INFO - 'NO_EM_RESTART': '1', 20:21:03 INFO - 'PAGER': '/bin/cat', 20:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:03 INFO - 'PWD': '/builds/slave/test', 20:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:03 INFO - 'SHELL': '/bin/bash', 20:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:03 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:03 INFO - 'USER': 'cltbld', 20:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:03 INFO - 'XPC_FLAGS': '0x0', 20:21:03 INFO - 'XPC_SERVICE_NAME': '0', 20:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:21:03 INFO - Cleaning up... 20:21:03 INFO - Return code: 0 20:21:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:03 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:21:03 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 20:21:03 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 20:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:03 INFO - 'HOME': '/Users/cltbld', 20:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:03 INFO - 'LOGNAME': 'cltbld', 20:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:03 INFO - 'MOZ_NO_REMOTE': '1', 20:21:03 INFO - 'NO_EM_RESTART': '1', 20:21:03 INFO - 'PAGER': '/bin/cat', 20:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:03 INFO - 'PWD': '/builds/slave/test', 20:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:03 INFO - 'SHELL': '/bin/bash', 20:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:03 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:03 INFO - 'USER': 'cltbld', 20:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:03 INFO - 'XPC_FLAGS': '0x0', 20:21:03 INFO - 'XPC_SERVICE_NAME': '0', 20:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:03 INFO - Downloading/unpacking blobuploader==1.2.4 20:21: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 20:21: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 20:21: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 20:21: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 20:21:05 INFO - Downloading blobuploader-1.2.4.tar.gz 20:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:21:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:21: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 20:21: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 20:21: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 20:21: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 20:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:21:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:06 INFO - Downloading docopt-0.6.1.tar.gz 20:21:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:21:06 INFO - Installing collected packages: blobuploader, requests, docopt 20:21:06 INFO - Running setup.py install for blobuploader 20:21:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:21:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:21:06 INFO - Running setup.py install for requests 20:21:06 INFO - Running setup.py install for docopt 20:21:07 INFO - Successfully installed blobuploader requests docopt 20:21:07 INFO - Cleaning up... 20:21:07 INFO - Return code: 0 20:21:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:21: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')]} 20:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21: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')]} 20:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:07 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:21:07 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 20:21:07 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 20:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:07 INFO - 'HOME': '/Users/cltbld', 20:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:07 INFO - 'LOGNAME': 'cltbld', 20:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:07 INFO - 'MOZ_NO_REMOTE': '1', 20:21:07 INFO - 'NO_EM_RESTART': '1', 20:21:07 INFO - 'PAGER': '/bin/cat', 20:21:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:07 INFO - 'PWD': '/builds/slave/test', 20:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:07 INFO - 'SHELL': '/bin/bash', 20:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:07 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:07 INFO - 'USER': 'cltbld', 20:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:07 INFO - 'XPC_FLAGS': '0x0', 20:21:07 INFO - 'XPC_SERVICE_NAME': '0', 20:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:21:07 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-4zNWrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:21:07 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-6HFXed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:21:07 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-hbRyiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:21:07 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-g6_oLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:21:07 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-Gk4wMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:21:07 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-n3IkTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-kIBtPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-eVqYPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-JFgH8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-L3XMz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-mUc83E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-cO9XSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-1dKEEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-wY7szq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-tSdaX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:21:08 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-cDz0LS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:21:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-m_uGAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:21:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:21:09 INFO - Running setup.py install for manifestparser 20:21:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:21:09 INFO - Running setup.py install for mozcrash 20:21:09 INFO - Running setup.py install for mozdebug 20:21:09 INFO - Running setup.py install for mozdevice 20:21:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:21:09 INFO - Running setup.py install for mozfile 20:21:09 INFO - Running setup.py install for mozhttpd 20:21:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Running setup.py install for mozinfo 20:21:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Running setup.py install for mozInstall 20:21:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Running setup.py install for mozleak 20:21:10 INFO - Running setup.py install for mozlog 20:21:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Running setup.py install for moznetwork 20:21:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:21:10 INFO - Running setup.py install for mozprocess 20:21:10 INFO - Running setup.py install for mozprofile 20:21:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:21:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:21:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:21:11 INFO - Running setup.py install for mozrunner 20:21:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:21:11 INFO - Running setup.py install for mozscreenshot 20:21:11 INFO - Running setup.py install for moztest 20:21:11 INFO - Running setup.py install for mozversion 20:21:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:21:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:21:11 INFO - Cleaning up... 20:21:11 INFO - Return code: 0 20:21:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:21:11 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')]} 20:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:11 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')]} 20:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:11 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:21:11 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 20:21:11 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 20:21:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:11 INFO - 'HOME': '/Users/cltbld', 20:21:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:11 INFO - 'LOGNAME': 'cltbld', 20:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:11 INFO - 'MOZ_NO_REMOTE': '1', 20:21:11 INFO - 'NO_EM_RESTART': '1', 20:21:11 INFO - 'PAGER': '/bin/cat', 20:21:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:11 INFO - 'PWD': '/builds/slave/test', 20:21:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:11 INFO - 'SHELL': '/bin/bash', 20:21:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:11 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:11 INFO - 'USER': 'cltbld', 20:21:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:11 INFO - 'XPC_FLAGS': '0x0', 20:21:11 INFO - 'XPC_SERVICE_NAME': '0', 20:21:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-7e02RW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-jQJyJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-IWGpwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-icQzwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-78XJQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-nxh291-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-HUfwMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-2oxf15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-GnNKb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:21:12 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)) 20:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:21:12 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-GJpbDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:21:13 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)) 20:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:21:13 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-bDWysm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:21:13 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)) 20:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:21:13 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-D9DcDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:21:13 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)) 20:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:21:13 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-7ivPDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:21:13 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)) 20:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:21:13 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-qXYEC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:21:13 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)) 20:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:13 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-MZ1pCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:21:13 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)) 20:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:21:13 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-LT3lwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:21:13 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)) 20:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:21:13 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-DDiqoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:21:13 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)) 20:21:13 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)) 20:21:13 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)) 20:21:13 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)) 20:21:13 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)) 20:21:13 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)) 20:21:13 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)) 20:21:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:21:13 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 20:21:13 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 20:21:13 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 20:21:13 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 20:21:15 INFO - Downloading blessings-1.6.tar.gz 20:21:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:21:15 INFO - Installing collected packages: blessings 20:21:15 INFO - Running setup.py install for blessings 20:21:15 INFO - Successfully installed blessings 20:21:15 INFO - Cleaning up... 20:21:15 INFO - Return code: 0 20:21:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:21:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:21:15 INFO - Reading from file tmpfile_stdout 20:21:15 INFO - Current package versions: 20:21:15 INFO - blessings == 1.6 20:21:15 INFO - blobuploader == 1.2.4 20:21:15 INFO - docopt == 0.6.1 20:21:15 INFO - functools32 == 3.2.3-2 20:21:15 INFO - jsonschema == 2.5.1 20:21:15 INFO - manifestparser == 1.1 20:21:15 INFO - mozInstall == 1.13 20:21:15 INFO - mozcrash == 1.0 20:21:15 INFO - mozdebug == 0.1 20:21:15 INFO - mozdevice == 0.51 20:21:15 INFO - mozfile == 1.2 20:21:15 INFO - mozhttpd == 0.7 20:21:15 INFO - mozinfo == 0.10 20:21:15 INFO - mozleak == 0.1 20:21:15 INFO - mozlog == 3.4 20:21:15 INFO - moznetwork == 0.27 20:21:15 INFO - mozprocess == 0.25 20:21:15 INFO - mozprofile == 0.28 20:21:15 INFO - mozrunner == 6.13 20:21:15 INFO - mozscreenshot == 0.1 20:21:15 INFO - mozsystemmonitor == 0.3 20:21:15 INFO - moztest == 0.8 20:21:15 INFO - mozversion == 1.4 20:21:15 INFO - psutil == 3.1.1 20:21:15 INFO - requests == 1.2.3 20:21:15 INFO - wsgiref == 0.1.2 20:21:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:15 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:21:15 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 20:21:15 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 20:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:15 INFO - 'HOME': '/Users/cltbld', 20:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:15 INFO - 'LOGNAME': 'cltbld', 20:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:15 INFO - 'MOZ_NO_REMOTE': '1', 20:21:15 INFO - 'NO_EM_RESTART': '1', 20:21:15 INFO - 'PAGER': '/bin/cat', 20:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:15 INFO - 'PWD': '/builds/slave/test', 20:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:15 INFO - 'SHELL': '/bin/bash', 20:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:15 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:15 INFO - 'USER': 'cltbld', 20:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:15 INFO - 'XPC_FLAGS': '0x0', 20:21:15 INFO - 'XPC_SERVICE_NAME': '0', 20:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:16 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)) 20:21:16 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)) 20:21:16 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)) 20:21:16 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)) 20:21:16 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)) 20:21:16 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)) 20:21:16 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)) 20:21:16 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)) 20:21:16 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)) 20:21:16 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:21:17 INFO - Downloading marionette_driver-1.4.0.tar.gz 20:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 20:21:17 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)) 20:21:17 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)) 20:21:17 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)) 20:21:17 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)) 20:21:17 INFO - Installing collected packages: marionette-driver 20:21:17 INFO - Running setup.py install for marionette-driver 20:21:17 INFO - Successfully installed marionette-driver 20:21:17 INFO - Cleaning up... 20:21:17 INFO - Return code: 0 20:21:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:21: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')]} 20:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:21: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')]} 20:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:21:17 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 0x10b662c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b666768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda0d80350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, '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.BpbKAeeUu3/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.INP0DctADJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:21:17 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 20:21:17 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 20:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:17 INFO - 'HOME': '/Users/cltbld', 20:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:17 INFO - 'LOGNAME': 'cltbld', 20:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:17 INFO - 'MOZ_NO_REMOTE': '1', 20:21:17 INFO - 'NO_EM_RESTART': '1', 20:21:17 INFO - 'PAGER': '/bin/cat', 20:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:17 INFO - 'PWD': '/builds/slave/test', 20:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:17 INFO - 'SHELL': '/bin/bash', 20:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:17 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:17 INFO - 'USER': 'cltbld', 20:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:17 INFO - 'XPC_FLAGS': '0x0', 20:21:17 INFO - 'XPC_SERVICE_NAME': '0', 20:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:21:18 INFO - Cleaning up... 20:21:18 INFO - Return code: 0 20:21:18 INFO - Running post-action listener: _resource_record_post_action 20:21:18 INFO - Running post-action listener: _start_resource_monitoring 20:21:18 INFO - Starting resource monitoring. 20:21:18 INFO - [mozharness: 2017-06-15 03:21:18.273030Z] Finished create-virtualenv step (success) 20:21:18 INFO - [mozharness: 2017-06-15 03:21:18.274189Z] Running install step. 20:21:18 INFO - Running pre-action listener: _resource_record_pre_action 20:21:18 INFO - Running main action method: install 20:21:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:21:18 INFO - Reading from file tmpfile_stdout 20:21:18 INFO - Detecting whether we're running mozinstall >=1.0... 20:21:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:21:18 INFO - Reading from file tmpfile_stdout 20:21:18 INFO - Output received: 20:21:18 INFO - Usage: mozinstall [options] installer 20:21:18 INFO - Options: 20:21:18 INFO - -h, --help show this help message and exit 20:21:18 INFO - -d DEST, --destination=DEST 20:21:18 INFO - Directory to install application into. [default: 20:21:18 INFO - "/builds/slave/test"] 20:21:18 INFO - --app=APP Application being installed. [default: firefox] 20:21:18 INFO - mkdir: /builds/slave/test/build/application 20:21:18 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'] 20:21:18 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 20:21:40 INFO - Reading from file tmpfile_stdout 20:21:40 INFO - Output received: 20:21:40 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:21:40 INFO - Running post-action listener: _resource_record_post_action 20:21:40 INFO - [mozharness: 2017-06-15 03:21:40.231932Z] Finished install step (success) 20:21:40 INFO - [mozharness: 2017-06-15 03:21:40.232097Z] Running setup-mitmproxy step. 20:21:40 INFO - Running pre-action listener: _resource_record_pre_action 20:21:40 INFO - Running main action method: setup_mitmproxy 20:21:40 INFO - Skipping: mitmproxy is not required 20:21:40 INFO - Running post-action listener: _resource_record_post_action 20:21:40 INFO - [mozharness: 2017-06-15 03:21:40.232789Z] Finished setup-mitmproxy step (success) 20:21:40 INFO - [mozharness: 2017-06-15 03:21:40.232922Z] Running run-tests step. 20:21:40 INFO - Running pre-action listener: _resource_record_pre_action 20:21:40 INFO - Running main action method: run_tests 20:21:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:21:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:21:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:21:40 INFO - 2017-06-14 20:21:40.247 screenresolution[1634:7624] starting screenresolution argv=screenresolution get 20:21:40 INFO - 2017-06-14 20:21:40.250 screenresolution[1634:7624] Display 0: 1600x1200x32@60 20:21:40 INFO - 2017-06-14 20:21:40.255 screenresolution[1635:7627] starting screenresolution argv=screenresolution list 20:21:40 INFO - Available Modes on Display 0 20:21:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:21:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:21:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:21:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:21:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:21:40 INFO - Intel Iris: 20:21:40 INFO - Chipset Model: Intel Iris 20:21:40 INFO - Type: GPU 20:21:40 INFO - Bus: Built-In 20:21:40 INFO - VRAM (Dynamic, Max): 1536 MB 20:21:40 INFO - Vendor: Intel (0x8086) 20:21:40 INFO - Device ID: 0x0a2e 20:21:40 INFO - Revision ID: 0x0009 20:21:40 INFO - Displays: 20:21:40 INFO - Display: 20:21:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:21:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:21:40 INFO - Display Serial Number: 1600x1200 60 20:21:40 INFO - Main Display: Yes 20:21:40 INFO - Mirror: Off 20:21:40 INFO - Online: Yes 20:21:40 INFO - Rotation: Supported 20:21:40 INFO - Return code: 0 20:21:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:21:40 INFO - Python 2.7.3 20:21:40 INFO - Return code: 0 20:21:40 INFO - grabbing minidump binary from tooltool 20:21:40 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')]} 20:21:40 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 0x10b3b8d30>, '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 0x10b382cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3a9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:21:40 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 20:21:40 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 20:21:40 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 20:21:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:21:40 INFO - Return code: 0 20:21:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:21:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:21:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:21:40 INFO - ENV: RUST_BACKTRACE is now 1 20:21:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:21:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:21:40 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/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 20:21:40 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/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0325 --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 20:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BpbKAeeUu3/Render', 20:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:21:40 INFO - 'HOME': '/Users/cltbld', 20:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:21:40 INFO - 'LOGNAME': 'cltbld', 20:21:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:21:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:40 INFO - 'MOZ_NO_REMOTE': '1', 20:21:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:21:40 INFO - 'NO_EM_RESTART': '1', 20:21:40 INFO - 'PAGER': '/bin/cat', 20:21:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:40 INFO - 'PWD': '/builds/slave/test', 20:21:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:21:40 INFO - 'RUST_BACKTRACE': '1', 20:21:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:21:40 INFO - 'SHELL': '/bin/bash', 20:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.INP0DctADJ/Listeners', 20:21:40 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:21:40 INFO - 'USER': 'cltbld', 20:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:40 INFO - 'XPC_FLAGS': '0x0', 20:21:40 INFO - 'XPC_SERVICE_NAME': '0', 20:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:21:40 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/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 20:21:40 INFO - mozversion application_buildid: 20170614193133 20:21:40 INFO - mozversion application_changeset: f5b4c1e362ec5c88ff5b08535c8277278c6a6713 20:21:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:21:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:21:40 INFO - mozversion application_name: Firefox 20:21:40 INFO - mozversion application_remotingname: firefox-dev 20:21:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:21:40 INFO - mozversion application_vendor: Mozilla 20:21:40 INFO - mozversion application_version: 55.0 20:21:40 INFO - mozversion platform_buildid: 20170614193133 20:21:40 INFO - mozversion platform_changeset: f5b4c1e362ec5c88ff5b08535c8277278c6a6713 20:21:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:21:40 INFO - mozversion platform_version: 55.0 20:21:40 INFO - using testdate: 1497496900 20:21:40 INFO - actual date: 1497496900 20:21:40 INFO - starting webserver on 'localhost:49227' 20:21:40 INFO - SUITE-START | Running 2 tests 20:21:40 INFO - TEST-START | dromaeo_css 20:21:40 INFO - operating with platform_type : mac_ 20:21:40 INFO - Initialising browser for dromaeo_css test... 20:21:40 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpByk7wF/profile 20:21:40 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 20:22:14 INFO - TEST-INFO | 1645: killed by SIGTERM 20:22:14 INFO - Browser initialized. 20:22:14 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:14 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpByk7wF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:22:16 INFO - PID 1653 | 2017-06-14 20:22:16.286 plugin-container[1654:8136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 20:22:16 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:16 INFO - PID 1653 | 2017-06-14 20:22:16.288 plugin-container[1654:8136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 20:22:16 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:16 INFO - PID 1653 | 2017-06-14 20:22:16.289 plugin-container[1654:8136] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:22:17 INFO - PID 1653 | 2017-06-14 20:22:17.056 plugin-container[1655:8182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 20:22:17 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:17 INFO - PID 1653 | 2017-06-14 20:22:17.057 plugin-container[1655:8182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 20:22:17 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:22:17 INFO - PID 1653 | 2017-06-14 20:22:17.057 plugin-container[1655:8182] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:22:26 INFO - PID 1653 | RSS: Main: 214818816 20:22:26 INFO - PID 1653 | 20:25:28 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 20:25:31 INFO - PID 1653 | RSS: Main: 229318656 20:25:31 INFO - PID 1653 | 20:28:33 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 20:28:33 INFO - PID 1653 | RSS: Main: 240316416 20:28:33 INFO - PID 1653 | 20:31:35 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 20:31:36 INFO - PID 1653 | RSS: Main: 244928512 20:31:36 INFO - PID 1653 | 20:34:37 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 20:34:38 INFO - PID 1653 | RSS: Main: 246644736 20:34:38 INFO - PID 1653 | 20:37:41 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 20:37:41 INFO - PID 1653 | RSS: Main: 249356288 20:37:41 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 20:40:43 INFO - PID 1653 | RSS: Main: 245215232 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | __start_tp_report 20:40:43 INFO - PID 1653 | _x_x_mozilla_page_load 20:40:43 INFO - PID 1653 | _x_x_mozilla_page_load_details 20:40:43 INFO - PID 1653 | |i|pagename|runs| 20:40:43 INFO - PID 1653 | |0;dojo.html;3027.7729170312227;3488.2325888304563;3608.548931383577;3632.8728494502698;3657.5062366580514;6301.054841773736;6324.399182077706;6339.651319177444;6341.556091073627;6348.460380867626;6375.362463753622;6379.489640828734;6402.39177278159;6410.871782564346;6414.775482858101;5573.888522229553;5600.243967064455;5604.719764011804;5683.232763576905;5727.656340619576;4779.737114458703;5637.577181711367;5637.689927054011;5657.8585535361735;5727.742251598688;5455.8363249102595;5478.698671573062;5535.889282214373;5618.887622247573;5673.375928647866;5476.288082549243;5516.14499752535;5561.221428999943;5565.109582466826;5578.804741834057;5497.780088796443;5558.360788509332;5596.188552659883;5644.5484361251;5647.717614119278;6311.463525600329;6332.588381755218;6369.681515924185;6373.044043393482;6383.170187875593;6888.965555172243;6918.688659010357;6932.48006399517;6941.895871561931;6947.756828511028;6769.898451523231;6874.175098988154;6987.930120698778;7013.33373329538;7013.614251216181;6394.26465956421;6403.7438502459845;6407.519436042222;6433.999999999954;6480.060391243338;7109.111361079865;7135.429165666734;7150.213476517579;7156.141263048364;7180.425565954711;6557.409833114947;6648.8670226595195;6653.2681405045405;6810.489213308922;6814.216365117975;6808.523403361717;6811.318868113248;6898.482613803984;6947.965260173768;6964.860702786017;6865.485088618374;6879.277675844064;6880.449564034865;6896.93103068973;6899.827504312432;6694.631795251211;6889.827754306182;6916.135483064617;6924.792256232321;6944.583325000516;4799.992001679617;5394;5424.620276580601;5462.781488740486;5503.147012213113;4751.097291514601;4760.571548560646;4762.9523704763205;4763.809447622057;4782.904341913142;5542.612017158841;5858.2677165354335;6337;6436.420722135031;6444.967775161188;7012.859742805217;7019.543729657556;7037.366637002694;7039.718411263492;7059.5764254144915;6412.5190610704385;6443.645599491979;6454.290028096905;6479.384458476437;6490.415862572391;4772.928406073912;4936.654434189572;5939.227900372924;6045.123457098696;6197.597156184834;6930.168379794457;7035.155781306276;7056.682449289897;7109.644517774194;7112.715491380493;5793.9420605795085;6888.931110688829;6955.6869940851775;6985.196702379291;7007.054047703495;7132.8930066048015;7140.107486563972;7140.250273721393;7160.319769621878;7164.2119366868565;6819.896946889055;6835.624040677811;6858.245592984668;6859.416949559343;6878.965605171942;6783.321667833177;6871.690773915086;6874.587524748531;6880.8967865483055;7005.334493223136;6687.899681504879;6697.162854643169;6720.428763555153;6773.62745049027;6795.830104247433;6806.251312355538;6810.8637827244165;6818.727250909908;6831.726730930906;6886.552374095668;6401.679916004275;6589.934100658932;6676.399124078856;6683.030960510601;6685.09751183584;6587.374199451045;6613.5370524062855;6622.933770662425;6631.171103612049;6636.502262330248;6607.405333520006;6613;6617.305182955721;6618.933810662024;6632.568883022588 20:40:43 INFO - PID 1653 | |1;ext.html;677.7085853083175;687.6046273392799;825.8761185822123;848.7581039403769;858.9012263589689;37850.24299514011;38740.41889371662;38742.80628596857;38821.80589097051;38942.80528597353;38411.69529828205;38628;39051.41422878652;39152.02119947003;39280;8345.582720863948;8435.70475033374;8476.406651534395;8482.151784821499;8501.404901656855;8175.672973081069;8226.917730822704;8325.542095184763;8329.750107496784;8335.54154521501;8296.958515207445;8318.251357377834;8328.583570821465;8341.040780310252;8367.539785311805;8286.792830179233;8314;8319.542425166614;8320.08479067305;8338.332933365316;8149;8212.219839115274;8215.34277257818;8225.259726624632;8237.46456480327;37158.628413715785;37168.628313717054;37261.44107838385;37387;37483;3595.2449985502813;3596.5863925648623;3617.8914632561064;3635;3637.5453068366455;30268.697313027027;30463.39073218523;30521.542176867366;30601.38797224043;30628.387432251453;3031.6058912341255;3141.858616362269;3173.8571764270664;3177.126290270171;3192.680731926812;260354;264732;264997.3500265011;319063;319306.80693193;47030.059398811834;47986.520134798906;48219.51780482221;48497;48566.27150592744;49652;49985.75007124941;50023.74988125109;50027.99944001099;50028.249576256385;15293.923530382277;15354;15377.384904603914;15425.922870385577;15444.15057171844;12628.368581570885;12825.29460879642;12838.743225135446;12855.742885142432;12856.164349317265;12120.575779847662;12166.57416990401;12231.388430578614;12237.93881030607;12326.383680815923;5115;5179.430262671103;5181.81863634771;5191.117510023305;5209.218617207373;991.3803872579638;992.7418871093568;1000.4197565412044;1062.2086545523634;1070.9143268538498;17965.820341796676;22692;22840.428989275402;23063.654045189334;24226.42528235659;1094.9835752463719;1106.30134535389;1106.9335839849634;1152.4525850221078;1178.0810967445407;11189.888101119048;11655.475503602358;11861.11041671878;11925.224860384047;12271.877281227253;352382;353034;353675;354582;354933;53852.65368365744;53996.46003539993;54112.729436352565;54231;54484.09305674282;1497.3561368611383;1504.6689728259764;1528.9694206115814;1534.12554843738;1536.0322996512125;21867.965131846548;22288.888555557118;22481.763503007493;22603.43491412728;23987.28038158858;32268.677313226566;37446.06384840401;37516.81241593775;37516.81241593884;37656.81171594124;294901;295605;295720;295882;296382.51808740816;32234.35531289314;32997.35013249376;33018.33963320768;34323;34366.65633343734;35869.1032724184;42932.35601466043;44503.55496444994;44576.55423445854;44587.77706111449;27321;28191.683453381713;28315.858420707766;29207.415851683272;30722.385552289277;29279.712303317017;30243.69756302497;30771.846140769154;30841.69158308388;30922.69077309198;36290.4556431659;36512.81743591265;36595.268094637424;36608.45087323639;36742.63257367392;28801.42397152087;29044.85477572683;29321.560176597795;29565.408691825614;31109.533357000117;22495.437614059778;24275.271741847773;24344.26967190987;24590.139345122836;24677.62983555212 20:40:43 INFO - PID 1653 | |2;jquery.html;464.28731896538807;504.93710738894623;511.11321856578854;514.9433562308144;535.8693157437804;21886.89056554715;21918.78081219187;22383;22983.77016229839;23064;23296.767032329673;23367.532649347002;23460.648090278617;23554.411139721517;23642.88178559109;6301.6849157542165;6479.8056058318325;6501.934980650204;6503.512236582252;6510.121133646944;6373.362663733636;6429.774957876475;6483;6497.545171837974;6499.772507962222;5888.911666325009;6160.260768707762;6185.59793613416;6195.907061394083;6210.65841378724;6120.663363515027;6136.263648362181;6165.506759459255;6168.167297414859;6175.536834737368;5606.495415412592;5618.494335509783;5652.293463317085;5654.4062873398525;5656.151577263402;28575.714242857513;28645.14064578045;28806.279843004093;29058.709412905813;29115.126546203646;2273.1362082408746;2317.3974766560646;2337.5324935012895;2338.590746619335;2339.8830058497006;18905.33831315911;19069.61860762777;19091.713624295648;19207.90396048025;19224.80775192258;2155.622766015941;2165.393689766852;2181.6618424144285;2202.9118835246572;2240.6526988316837;128605;169153;169515;192900.03549982302;200981;14062.648433789238;14530.491432799801;15046.54860354191;15373.231338433034;15506.689866202612;20056.49858753543;20222.797772022386;20230;20242.291519796738;20275.898620506803;9058.429066124287;9233;10319.74200644975;10326.01902819231;10348.637797677045;8241.299489543337;9397.389169704084;9677.854832177523;9776;9799.97100304472;8976.576324392378;9075.13786190311;9112.726618201465;9121.407108537924;9135.862962055575;2659.8670066496597;2676.4038882284153;2680.8793604287853;2681.927229108372;2774.0845520978028;893.5784025291404;913.3104506097853;934.3039435620507;954.4321128928276;990.6879333010079;19979.800201998085;20322.390328290174;20467.07898144587;20990.895045524674;21024.15903363879;970.8106919150839;980.1374790184592;986.8421052631608;1031.4430207687915;1038.46000079958;8582.356323275779;9309.381315664567;10644.733881653021;11298.717532061764;14435.927820361041;585905.6213352187;601160;610002;613916.930415354;617483;170056;170330.14834925745;171134;173927.13036434737;175555;965.4786415335781;1067.5516283160935;1142.1319796954374;1223.7980733178927;1242.0250103668577;21559.35321940344;22264.666030009237;23153.189638362575;23167.420814479774;23320.60076395422;68136.97794533185;69376.61246774935;71425.6428717874;72349;72478.63760681162;94361.58457623005;94367;97316;98374.5081274589;98832.50583747035;11315.490802913724;11425.257358271685;11999.76000480003;12084.335361555199;12142.150049496804;18379;18562;18582.535436614195;18780.436586902415;18901.621967560295;13047.347632618521;14399.78400324017;14920.179390133648;14989.250537472864;15297.23513824282;12915.22508649484;13281.933590331986;13675.521356752466;13748.587542373745;13898.367748672072;15363.001404908646;15448.922755386151;15515.301811418287;15660.68678626444;15678.529644110695;9752;11806.881931180578;11845.23006004607;11854.229475084092;11873.465694043616;14386.992910496165;14959.77560336574;15030;15171.089734615958;15300.693986120437 20:40:43 INFO - PID 1653 | |3;mootools.html;1228.2016689152056;1234.5555599984004;1439.7689975071314;1439.8128243328358;1449.7028109237608;13589.93205033976;13988;14057.086289391182;14154.221517816515;14179.716405671881;10835.620753273637;14008.089474184171;14014.22921739304;14029;14052.227127507984;2788.6374771279757;2817.197098826835;2834.078924349584;2837.1488553433887;2837.6594808622995;2784.5683918992486;2819.238805522508;2823.887044518217;2824.844633545154;2827.307309709124;2305.873176975266;2767.4465106978582;2817.5632776919506;2821.083147976916;2831.377097038648;2746.5880117982265;2774.0290898185676;2775.139706690922;2775.653043369589;2781.193453898367;2638.22172459125;2746.945061098787;2754.724527547229;2754.986225068882;2768.321761168527;13504.594862154152;13594.864051359458;13595.116317439335;13631.659208519768;13645.658858528615;7509.662065207079;7798.25916537928;7808.64861081247;7815.960920195363;7835.451518393715;3774.9811250943844;4831.275308703723;4919.60547222419;4932.926006109876;4945.629077819178;1388.3960477192352;1397.8532254113375;1403.943842246309;1423.5515812519127;1429.0639631041581;19110.426687199408;19636.50908727279;20505.38483845487;20508.077136528744;20631.381058568117;6064.241969753781;6730.091437655951;6921.6193109379465;6938.167419909643;6997.615131167835;14442.855571444152;14515.983881128219;14528.782068268985;14552.63618409527;14598.781018284733;7204.9639751800905;7553.660085296175;7563.357114645317;7581;7586.089669239726;6837.589744615339;7047.823804404931;7087;7109.8933515997305;7143.24995875436;5293.682379057269;5318.361796584357;5385.299911011622;5416.187571864252;5430.619856609999;1521.6576270339085;1540.2067935013476;1542.4986879264284;1554.759012353087;1564.7965764450548;816.4611356504679;843.6878355008686;853.7865533616596;861.1603686405705;874.903760586335;7197;7251.419886409075;7427.18300986891;7508.511946723445;7545.283198096172;591.4410881716823;677.861038487109;702.153904545022;702.5714314268292;721.5346101764437;4007.73949693269;4449.7997590108525;4987.276844857475;5063.443021267658;5079.6698214615935;18353.81646183521;18865.339713109974;19017.61964760697;19021.14404851785;19091.61816763657;15306.770398444032;18269.269229230682;18348.724769128472;18927.52681182954;19004.61990760177;9472.631842039491;9738.707838764849;9829;9854.852177217348;10043.045910638331;7750.612469376621;7876.921230787619;7896.447248692536;7910.841783164189;8271.379646526415;16532.83467165313;17169.828301717323;17257.0508622027;17272.395466158625;17273.91363043177;15553.68892622164;15599.688006239585;16784.496465106065;17059.65880682404;18056.36802711951;9788.951055244679;9912.860021097666;9971.252156088178;9974.40153590787;10012.249081318785;12554.748905021666;12679.302638354979;12700.555480558136;12701.93649031749;12705;10065.345752526062;10180.949095254773;10335.000697503072;10497.240318719658;10497.895021049997;9978.451185184884;10003.449810260505;10092.444915529422;10335.586576536854;10400.531976060944;9226.538673066454;9472.242220622471;9502.287328450255;9572.90427095748;9591.136797688241;6838.316168383122;6883.8967415487805;6959.446348877645;7117.3238542338395;7197.46019048563;7015.754448594274;7095.716171353088;7280.745173918887;7326.157491888501;7375.631218438949 20:40:43 INFO - PID 1653 | |4;prototype.html;430.6942071129498;438.1827890983313;462.13119335648986;468.57359802579657;472.06295503425696;6550.377714117157;6561.77033803817;6572.934270657298;6621.443960669239;6639.302873198317;5559.8888022239535;6059.909331280701;6154.323024467305;6409.718056388718;6456.7094480748365;4786.784594693229;4797.424309082915;4799.544043315879;4800.207965685667;4822.541858523435;3969.5114332125454;4781.354517140194;4793.2810078488155;4803.382156724593;4812.831550895719;3909.824057917386;4551.611758413683;4728.66899317048;4743.454502732178;4798.304245884362;4695.741734204617;4729.929051064237;4749.525047495257;4754.358161648168;4770.61835053195;4131.421600975866;4151.546958564479;4176.1230141670285;4187.120704652027;4189.282394218386;5314.4056782965345;6132.356102609207;6137.754489820447;6165.982612868871;6214.502839772807;5603.7478313475995;5612.41069687681;5615.5507559395555;5640.464155905182;5658.264425624642;6815.965920170417;6831.760888368933;6834.658267086676;6855;6877.690503927335;4779.569838714498;4781.760911954389;4794.760261986921;4799.352087468182;4804.735739534324;7128.358447739676;7166.570005799668;7237.746678866214;7245.529040612343;7339.522931009467;4674.929876051862;4860.8819971406065;4863.8540843774745;4870.975645121823;4893.584045356185;4719.764011799396;4937.1606826839525;4938.678985865908;4944.011197760462;4972.676776009548;5653.095504719226;5665.603407761417;5673.461021203062;5674.574406919415;5696.1455781633085;6348.619082855043;6868.111269400954;6876.071730316343;6878;6909.7581584644295;1416.872481476672;1632.379469082376;1677.379369633219;1678.5551828765388;1686.7638530605725;1052.9315594486486;1113.9164562657834;1135.761490087091;1143.4111432612212;1153.2734377342222;6209.6895155242055;6323.146375239376;6405.19935008124;6412.583182093149;6419.143481150936;5862.912056319158;7095.50331476802;7153;7176.246494118147;7201;6046.395360463918;6332.467013194759;6332.651704156316;6336.271328797156;6385.010323399881;3260.6087269527334;3673.504076949631;3681.9815900920325;3708.7589306694977;3733.701303895682;6352.7141278642575;7299.635018249066;7317.0487836580905;7342.70629174827;7343.816404589821;7254.238304977901;7273;7322.912969803687;7332.30343117403;7344;6900.6204658744255;7175.569465832067;7296.16094149169;7296.489245752747;7326.706931722778;7065.894011589728;7085.574865508086;7089.61007144612;7090.149182098181;7102.14774227096;5802.5783682997935;6964.616946067812;6967.616781077089;6973.372396484341;6978.685959131953;6096.268447786117;6753.189617245962;6889.276625954403;7095.574265544084;7275.308845659654;4265.2749032663905;4511.639068874548;4825.3485779420735;4865.245886887538;4874.293227481924;7093.645317733989;7106.822329441598;7118.181409137808;7128.251533589106;7129.679164437509;5180.48195180479;5593.133064375028;6830.521863469509;6855.177378714586;6881.4494840413645;6239.970404883325;6631.734730610721;6660.93768735774;6866.6566671667215;6871.347222013901;5260.815871444481;5688.943110569007;5894.705264736832;6232.753449310066;6724.007032685952;6238.500919926304;6296.968515157395;6724.899126513008;6799.320067993359;6824.078749368771;5528.612997090326;5587.720613969204;6410.006449000412;6648.202215734143;6681.899771503528 20:40:43 INFO - PID 1653 | |5;yui.html;790.225578932646;833.8332333533294;838.9370797190212;869.9826003479931;877.333226747672;14279.928600356996;17068.658626827455;17244.96530208185;17261.65476690467;17271.39550115746;15354.769678454804;15700.21498925055;15918.363265469396;16255.349786008546;16340.673186536293;983.5819776594955;1003.0471052500118;1006.607423104986;1006.8942761010098;1007.7833265847842;1007.8639383683183;1013.8935411781767;1014.2089170447026;1015.7968406318765;1017.3793985668736;1054.6888667843004;1058.30680904008;1059.750958524746;1060.2366296266675;1060.6181774561153;998.3710262534629;1040.089921318846;1040.750219947211;1048.6434612231878;1050.978980420395;771.0208035794566;772.1467778105169;774.8062984253936;774.8217909880759;776.3827756933251;17133.400330988356;17512.12439378026;17516.474505764847;17700.734488982675;17734.645307093913;14350.856491435161;17678.91160544202;18014.36949706754;18179.27282908682;18322.35871744496;14693.118412895154;14922.626934326729;14951.626209344744;15099.849001509954;15112.622184445478;3622.5834029086727;3625.6918161956264;3634.7092232621326;3646.5441819772527;3647.74465787395;93663.53168234116;137209;141371.29314353364;160292;163725.3627463722;11437.828432573519;11663.650090497298;14295.142291462545;14366.928165359106;14429.639259018608;26313.34216644561;26613.20160395191;26672.666366681588;26828.061017864282;26849.926002960052;7767.339776119094;7806.1413244543055;7922.683092676343;7972.3622110231045;8061.838763224702;4053.412255222976;4136.834526618962;4166.0834616384345;4176.644985176234;4178.749275043507;4735.981763920705;4767.451743049524;4804.711717296974;4832.975835120872;4838.782254798542;880.2869675562814;1041.807265655856;1048.114343379843;1058.0847566854616;1060.8408738689259;629.3109045595066;637.4326849104301;637.4517914593446;649.6621756686496;671.3554987212233;11339.773204535863;11557.479913403917;14189.148651080968;14284.642883927778;14286.428542858377;611.764236242796;639.5011890725242;661.8874791285493;667.6027763480721;674.1169068520254;4945.282933974553;5622.240997465372;5718.485336319751;5805.419458054161;6027.60820546663;131584;138877;139231;140531;144790;118010.40994794971;120380;120782.396088019;125432.1185182223;127546;92776.53611732033;107705.4614726937;108613;111168;111614;12635;12663.620091397273;12667;12709.237445753284;13198.80201796993;33554;43926.34110488281;45052.549474504835;46784.298235527174;47340.999999998625;83499.5825020871;85697.57151214454;86032.13967860241;89300.6604900914;89682.55158724164;17634.294628214728;18370.35703750362;18370.540736481697;18435;18483.90758046201;18251;18614.53463663419;18943.526411839262;18985.810141898404;19177.712334315274;17149.056801876017;17489.82510174882;17745.201465933806;18229.817701822813;18370.908145459187;17457.912710436365;17995.2801887923;18230.635387292445;18354.541136471697;18456;16668.16659167061;17428.128593570524;17564.912175439553;17922;18026.63946721032;9373.66271153488;11693.356865372487;11764.882351176379;12278.570250041206;12314.384280785745;13275;13893.72212555723;14069.507567235507;14489;14514.85485145135 20:40:43 INFO - PID 1653 | __end_tp_report 20:40:43 INFO - PID 1653 | __start_cc_report 20:40:43 INFO - PID 1653 | _x_x_mozilla_cycle_collect,406 20:40:43 INFO - PID 1653 | __end_cc_report 20:40:43 INFO - PID 1653 | __startTimestamp1497498043685__endTimestamp 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | ------- Summary: start ------- 20:40:43 INFO - PID 1653 | Number of tests: 6 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | [#0] dojo.html Cycles:165 Average:6317.56 Median:6613.54 stddev:813.73 (12.3%) stddev-sans-first:774.22 20:40:43 INFO - PID 1653 | Values: 3027.8 3488.2 3608.5 3632.9 3657.5 6301.1 6324.4 6339.7 6341.6 6348.5 6375.4 6379.5 6402.4 6410.9 6414.8 5573.9 5600.2 5604.7 5683.2 5727.7 4779.7 5637.6 5637.7 5657.9 5727.7 5455.8 5478.7 5535.9 5618.9 5673.4 5476.3 5516.1 5561.2 5565.1 5578.8 5497.8 5558.4 5596.2 5644.5 5647.7 6311.5 6332.6 6369.7 6373.0 6383.2 6889.0 6918.7 6932.5 6941.9 6947.8 6769.9 6874.2 6987.9 7013.3 7013.6 6394.3 6403.7 6407.5 6434.0 6480.1 7109.1 7135.4 7150.2 7156.1 7180.4 6557.4 6648.9 6653.3 6810.5 6814.2 6808.5 6811.3 6898.5 6948.0 6964.9 6865.5 6879.3 6880.4 6896.9 6899.8 6694.6 6889.8 6916.1 6924.8 6944.6 4800.0 5394.0 5424.6 5462.8 5503.1 4751.1 4760.6 4763.0 4763.8 4782.9 5542.6 5858.3 6337.0 6436.4 6445.0 7012.9 7019.5 7037.4 7039.7 7059.6 6412.5 6443.6 6454.3 6479.4 6490.4 4772.9 4936.7 5939.2 6045.1 6197.6 6930.2 7035.2 7056.7 7109.6 7112.7 5793.9 6888.9 6955.7 6985.2 7007.1 7132.9 7140.1 7140.3 7160.3 7164.2 6819.9 6835.6 6858.2 6859.4 6879.0 6783.3 6871.7 6874.6 6880.9 7005.3 6687.9 6697.2 6720.4 6773.6 6795.8 6806.3 6810.9 6818.7 6831.7 6886.6 6401.7 6589.9 6676.4 6683.0 6685.1 6587.4 6613.5 6622.9 6631.2 6636.5 6607.4 6613.0 6617.3 6618.9 6632.6 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | [#1] ext.html Cycles:180 Average:45811.98 Median:353354.50 stddev:82445.90 (23.3%) stddev-sans-first:82607.54 20:40:43 INFO - PID 1653 | Values: 677.7 687.6 825.9 848.8 858.9 37850.2 38740.4 38742.8 38821.8 38942.8 38411.7 38628.0 39051.4 39152.0 39280.0 8345.6 8435.7 8476.4 8482.2 8501.4 8175.7 8226.9 8325.5 8329.8 8335.5 8297.0 8318.3 8328.6 8341.0 8367.5 8286.8 8314.0 8319.5 8320.1 8338.3 8149.0 8212.2 8215.3 8225.3 8237.5 37158.6 37168.6 37261.4 37387.0 37483.0 3595.2 3596.6 3617.9 3635.0 3637.5 30268.7 30463.4 30521.5 30601.4 30628.4 3031.6 3141.9 3173.9 3177.1 3192.7 260354.0 264732.0 264997.4 319063.0 319306.8 47030.1 47986.5 48219.5 48497.0 48566.3 49652.0 49985.8 50023.7 50028.0 50028.2 15293.9 15354.0 15377.4 15425.9 15444.2 12628.4 12825.3 12838.7 12855.7 12856.2 12120.6 12166.6 12231.4 12237.9 12326.4 5115.0 5179.4 5181.8 5191.1 5209.2 991.4 992.7 1000.4 1062.2 1070.9 17965.8 22692.0 22840.4 23063.7 24226.4 1095.0 1106.3 1106.9 1152.5 1178.1 11189.9 11655.5 11861.1 11925.2 12271.9 352382.0 353034.0 353675.0 354582.0 354933.0 53852.7 53996.5 54112.7 54231.0 54484.1 1497.4 1504.7 1529.0 1534.1 1536.0 21868.0 22288.9 22481.8 22603.4 23987.3 32268.7 37446.1 37516.8 37516.8 37656.8 294901.0 295605.0 295720.0 295882.0 296382.5 32234.4 32997.4 33018.3 34323.0 34366.7 35869.1 42932.4 44503.6 44576.6 44587.8 27321.0 28191.7 28315.9 29207.4 30722.4 29279.7 30243.7 30771.8 30841.7 30922.7 36290.5 36512.8 36595.3 36608.5 36742.6 28801.4 29044.9 29321.6 29565.4 31109.5 22495.4 24275.3 24344.3 24590.1 24677.6 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | [#2] jquery.html Cycles:180 Average:40859.06 Median:12742.41 stddev:103985.00 (816.1%) stddev-sans-first:104232.47 20:40:43 INFO - PID 1653 | Values: 464.3 504.9 511.1 514.9 535.9 21886.9 21918.8 22383.0 22983.8 23064.0 23296.8 23367.5 23460.6 23554.4 23642.9 6301.7 6479.8 6501.9 6503.5 6510.1 6373.4 6429.8 6483.0 6497.5 6499.8 5888.9 6160.3 6185.6 6195.9 6210.7 6120.7 6136.3 6165.5 6168.2 6175.5 5606.5 5618.5 5652.3 5654.4 5656.2 28575.7 28645.1 28806.3 29058.7 29115.1 2273.1 2317.4 2337.5 2338.6 2339.9 18905.3 19069.6 19091.7 19207.9 19224.8 2155.6 2165.4 2181.7 2202.9 2240.7 128605.0 169153.0 169515.0 192900.0 200981.0 14062.6 14530.5 15046.5 15373.2 15506.7 20056.5 20222.8 20230.0 20242.3 20275.9 9058.4 9233.0 10319.7 10326.0 10348.6 8241.3 9397.4 9677.9 9776.0 9800.0 8976.6 9075.1 9112.7 9121.4 9135.9 2659.9 2676.4 2680.9 2681.9 2774.1 893.6 913.3 934.3 954.4 990.7 19979.8 20322.4 20467.1 20990.9 21024.2 970.8 980.1 986.8 1031.4 1038.5 8582.4 9309.4 10644.7 11298.7 14435.9 585905.6 601160.0 610002.0 613916.9 617483.0 170056.0 170330.1 171134.0 173927.1 175555.0 965.5 1067.6 1142.1 1223.8 1242.0 21559.4 22264.7 23153.2 23167.4 23320.6 68137.0 69376.6 71425.6 72349.0 72478.6 94361.6 94367.0 97316.0 98374.5 98832.5 11315.5 11425.3 11999.8 12084.3 12142.2 18379.0 18562.0 18582.5 18780.4 18901.6 13047.3 14399.8 14920.2 14989.3 15297.2 12915.2 13281.9 13675.5 13748.6 13898.4 15363.0 15448.9 15515.3 15660.7 15678.5 9752.0 11806.9 11845.2 11854.2 11873.5 14387.0 14959.8 15030.0 15171.1 15300.7 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | [#3] mootools.html Cycles:180 Average:8375.13 Median:2817.38 stddev:5556.21 (197.2%) stddev-sans-first:5545.84 20:40:43 INFO - PID 1653 | Values: 1228.2 1234.6 1439.8 1439.8 1449.7 13589.9 13988.0 14057.1 14154.2 14179.7 10835.6 14008.1 14014.2 14029.0 14052.2 2788.6 2817.2 2834.1 2837.1 2837.7 2784.6 2819.2 2823.9 2824.8 2827.3 2305.9 2767.4 2817.6 2821.1 2831.4 2746.6 2774.0 2775.1 2775.7 2781.2 2638.2 2746.9 2754.7 2755.0 2768.3 13504.6 13594.9 13595.1 13631.7 13645.7 7509.7 7798.3 7808.6 7816.0 7835.5 3775.0 4831.3 4919.6 4932.9 4945.6 1388.4 1397.9 1403.9 1423.6 1429.1 19110.4 19636.5 20505.4 20508.1 20631.4 6064.2 6730.1 6921.6 6938.2 6997.6 14442.9 14516.0 14528.8 14552.6 14598.8 7205.0 7553.7 7563.4 7581.0 7586.1 6837.6 7047.8 7087.0 7109.9 7143.2 5293.7 5318.4 5385.3 5416.2 5430.6 1521.7 1540.2 1542.5 1554.8 1564.8 816.5 843.7 853.8 861.2 874.9 7197.0 7251.4 7427.2 7508.5 7545.3 591.4 677.9 702.2 702.6 721.5 4007.7 4449.8 4987.3 5063.4 5079.7 18353.8 18865.3 19017.6 19021.1 19091.6 15306.8 18269.3 18348.7 18927.5 19004.6 9472.6 9738.7 9829.0 9854.9 10043.0 7750.6 7876.9 7896.4 7910.8 8271.4 16532.8 17169.8 17257.1 17272.4 17273.9 15553.7 15599.7 16784.5 17059.7 18056.4 9789.0 9912.9 9971.3 9974.4 10012.2 12554.7 12679.3 12700.6 12701.9 12705.0 10065.3 10180.9 10335.0 10497.2 10497.9 9978.5 10003.4 10092.4 10335.6 10400.5 9226.5 9472.2 9502.3 9572.9 9591.1 6838.3 6883.9 6959.4 7117.3 7197.5 7015.8 7095.7 7280.7 7326.2 7375.6 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | [#4] prototype.html Cycles:180 Average:5564.00 Median:6146.04 stddev:1710.76 (27.8%) stddev-sans-first:1671.61 20:40:43 INFO - PID 1653 | Values: 430.7 438.2 462.1 468.6 472.1 6550.4 6561.8 6572.9 6621.4 6639.3 5559.9 6059.9 6154.3 6409.7 6456.7 4786.8 4797.4 4799.5 4800.2 4822.5 3969.5 4781.4 4793.3 4803.4 4812.8 3909.8 4551.6 4728.7 4743.5 4798.3 4695.7 4729.9 4749.5 4754.4 4770.6 4131.4 4151.5 4176.1 4187.1 4189.3 5314.4 6132.4 6137.8 6166.0 6214.5 5603.7 5612.4 5615.6 5640.5 5658.3 6816.0 6831.8 6834.7 6855.0 6877.7 4779.6 4781.8 4794.8 4799.4 4804.7 7128.4 7166.6 7237.7 7245.5 7339.5 4674.9 4860.9 4863.9 4871.0 4893.6 4719.8 4937.2 4938.7 4944.0 4972.7 5653.1 5665.6 5673.5 5674.6 5696.1 6348.6 6868.1 6876.1 6878.0 6909.8 1416.9 1632.4 1677.4 1678.6 1686.8 1052.9 1113.9 1135.8 1143.4 1153.3 6209.7 6323.1 6405.2 6412.6 6419.1 5862.9 7095.5 7153.0 7176.2 7201.0 6046.4 6332.5 6332.7 6336.3 6385.0 3260.6 3673.5 3682.0 3708.8 3733.7 6352.7 7299.6 7317.0 7342.7 7343.8 7254.2 7273.0 7322.9 7332.3 7344.0 6900.6 7175.6 7296.2 7296.5 7326.7 7065.9 7085.6 7089.6 7090.1 7102.1 5802.6 6964.6 6967.6 6973.4 6978.7 6096.3 6753.2 6889.3 7095.6 7275.3 4265.3 4511.6 4825.3 4865.2 4874.3 7093.6 7106.8 7118.2 7128.3 7129.7 5180.5 5593.1 6830.5 6855.2 6881.4 6240.0 6631.7 6660.9 6866.7 6871.3 5260.8 5688.9 5894.7 6232.8 6724.0 6238.5 6297.0 6724.9 6799.3 6824.1 5528.6 5587.7 6410.0 6648.2 6681.9 20:40:43 INFO - PID 1653 | 20:40:43 INFO - PID 1653 | [#5] yui.html Cycles:180 Average:25986.29 Median:17689.82 stddev:39400.39 (222.7%) stddev-sans-first:39465.50 20:40:43 INFO - PID 1653 | Values: 790.2 833.8 838.9 870.0 877.3 14279.9 17068.7 17245.0 17261.7 17271.4 15354.8 15700.2 15918.4 16255.3 16340.7 983.6 1003.0 1006.6 1006.9 1007.8 1007.9 1013.9 1014.2 1015.8 1017.4 1054.7 1058.3 1059.8 1060.2 1060.6 998.4 1040.1 1040.8 1048.6 1051.0 771.0 772.1 774.8 774.8 776.4 17133.4 17512.1 17516.5 17700.7 17734.6 14350.9 17678.9 18014.4 18179.3 18322.4 14693.1 14922.6 14951.6 15099.8 15112.6 3622.6 3625.7 3634.7 3646.5 3647.7 93663.5 137209.0 141371.3 160292.0 163725.4 11437.8 11663.7 14295.1 14366.9 14429.6 26313.3 26613.2 26672.7 26828.1 26849.9 7767.3 7806.1 7922.7 7972.4 8061.8 4053.4 4136.8 4166.1 4176.6 4178.7 4736.0 4767.5 4804.7 4833.0 4838.8 880.3 1041.8 1048.1 1058.1 1060.8 629.3 637.4 637.5 649.7 671.4 11339.8 11557.5 14189.1 14284.6 14286.4 611.8 639.5 661.9 667.6 674.1 4945.3 5622.2 5718.5 5805.4 6027.6 131584.0 138877.0 139231.0 140531.0 144790.0 118010.4 120380.0 120782.4 125432.1 127546.0 92776.5 107705.5 108613.0 111168.0 111614.0 12635.0 12663.6 12667.0 12709.2 13198.8 33554.0 43926.3 45052.5 46784.3 47341.0 83499.6 85697.6 86032.1 89300.7 89682.6 17634.3 18370.4 18370.5 18435.0 18483.9 18251.0 18614.5 18943.5 18985.8 19177.7 17149.1 17489.8 17745.2 18229.8 18370.9 17457.9 17995.3 18230.6 18354.5 18456.0 16668.2 17428.1 17564.9 17922.0 18026.6 9373.7 11693.4 11764.9 12278.6 12314.4 13275.0 13893.7 14069.5 14489.0 14514.9 20:40:43 INFO - PID 1653 | -------- Summary: end -------- 20:40:43 INFO - PID 1653 | 20:40:44 INFO - TEST-INFO | 1653: exit 0 20:40:44 INFO - TEST-OK | dromaeo_css | took 1143394ms 20:40:44 INFO - TEST-START | kraken 20:40:44 INFO - operating with platform_type : mac_ 20:40:44 INFO - Initialising browser for kraken test... 20:40:44 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpAF5LCZ/profile 20:40:44 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 20:41:17 INFO - TEST-INFO | 1664: killed by SIGTERM 20:41:17 INFO - Browser initialized. 20:41:17 INFO - Running cycle 1/1 for kraken test... 20:41:17 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpAF5LCZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:19 INFO - PID 1668 | 2017-06-14 20:41:19.112 plugin-container[1669:10721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 20:41:19 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:19 INFO - PID 1668 | 2017-06-14 20:41:19.113 plugin-container[1669:10721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 20:41:19 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:19 INFO - PID 1668 | 2017-06-14 20:41:19.114 plugin-container[1669:10721] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:41:19 INFO - PID 1668 | 2017-06-14 20:41:19.929 plugin-container[1670:10767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 20:41:19 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:19 INFO - PID 1668 | 2017-06-14 20:41:19.930 plugin-container[1670:10767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 20:41:19 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:41:19 INFO - PID 1668 | 2017-06-14 20:41:19.930 plugin-container[1670:10767] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:41:29 INFO - PID 1668 | RSS: Main: 214798336 20:41:29 INFO - PID 1668 | 20:41:32 INFO - PID 1668 | 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) 20:41:33 INFO - PID 1668 | RSS: Main: 228495360 20:41:33 INFO - PID 1668 | 20:41:35 INFO - PID 1668 | 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) 20:41:36 INFO - PID 1668 | RSS: Main: 237690880 20:41:36 INFO - PID 1668 | 20:41:39 INFO - PID 1668 | 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) 20:41:40 INFO - PID 1668 | RSS: Main: 242429952 20:41:40 INFO - PID 1668 | 20:41:42 INFO - PID 1668 | 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) 20:41:43 INFO - PID 1668 | RSS: Main: 245198848 20:41:43 INFO - PID 1668 | 20:41:44 INFO - PID 1668 | 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) 20:41:45 INFO - PID 1668 | RSS: Main: 245133312 20:41:45 INFO - PID 1668 | 20:41:48 INFO - PID 1668 | 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) 20:41:49 INFO - PID 1668 | RSS: Main: 251244544 20:41:49 INFO - PID 1668 | 20:41:52 INFO - PID 1668 | 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) 20:41:53 INFO - PID 1668 | RSS: Main: 251551744 20:41:53 INFO - PID 1668 | 20:41:55 INFO - PID 1668 | 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) 20:41:56 INFO - PID 1668 | RSS: Main: 251260928 20:41:56 INFO - PID 1668 | 20:41:58 INFO - PID 1668 | 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) 20:41:58 INFO - PID 1668 | RSS: Main: 250785792 20:41:58 INFO - PID 1668 | 20:42:00 INFO - PID 1668 | 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) 20:42:01 INFO - PID 1668 | RSS: Main: 251092992 20:42:01 INFO - PID 1668 | 20:42:03 INFO - PID 1668 | 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) 20:42:04 INFO - PID 1668 | RSS: Main: 250716160 20:42:04 INFO - PID 1668 | 20:42:06 INFO - PID 1668 | 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) 20:42:07 INFO - PID 1668 | RSS: Main: 250769408 20:42:07 INFO - PID 1668 | 20:42:10 INFO - PID 1668 | 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) 20:42:11 INFO - PID 1668 | RSS: Main: 250912768 20:42:11 INFO - PID 1668 | 20:42:12 INFO - PID 1668 | 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) 20:42:13 INFO - PID 1668 | RSS: Main: 252219392 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | __start_tp_report 20:42:13 INFO - PID 1668 | _x_x_mozilla_page_load 20:42:13 INFO - PID 1668 | _x_x_mozilla_page_load_details 20:42:13 INFO - PID 1668 | |i|pagename|runs| 20:42:13 INFO - PID 1668 | |0;ai-astar;111;117;102;121;164;100;161;98;161;171 20:42:13 INFO - PID 1668 | |1;audio-beat-detection;141;145;178;140;113;128;111;158;169;146 20:42:13 INFO - PID 1668 | |2;audio-dft;198;201;204;204;200;198;197;199;201;196 20:42:13 INFO - PID 1668 | |3;audio-fft;67;94;118;201;89;53;70;80;157;84 20:42:13 INFO - PID 1668 | |4;audio-oscillator;69;70;121;69;68;75;104;71;71;113 20:42:13 INFO - PID 1668 | |5;imaging-gaussian-blur;96;97;95;95;123;93;141;143;96;110 20:42:13 INFO - PID 1668 | |6;imaging-darkroom;135;155;98;98;99;98;99;98;99;134 20:42:13 INFO - PID 1668 | |7;imaging-desaturate;92;92;91;92;92;91;89;95;93;93 20:42:13 INFO - PID 1668 | |8;json-parse-financial;50;49;48;51;57;66;78;78;81;43 20:42:13 INFO - PID 1668 | |9;json-stringify-tinderbox;55;62;80;89;82;61;61;55;53;56 20:42:13 INFO - PID 1668 | |10;stanford-crypto-aes;80;82;81;81;82;82;80;81;82;81 20:42:13 INFO - PID 1668 | |11;stanford-crypto-ccm;136;137;140;153;142;137;144;146;141;167 20:42:13 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;176;179;181;181;177;178;178;182;178;205 20:42:13 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;69;67;63;65;67;63;64;67;66;67 20:42:13 INFO - PID 1668 | __end_tp_report 20:42:13 INFO - PID 1668 | __start_cc_report 20:42:13 INFO - PID 1668 | _x_x_mozilla_cycle_collect,1044 20:42:13 INFO - PID 1668 | __end_cc_report 20:42:13 INFO - PID 1668 | __startTimestamp1497498133176__endTimestamp 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | ------- Summary: start ------- 20:42:13 INFO - PID 1668 | Number of tests: 14 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:130.60 Median:161.00 stddev:29.95 (18.6%) stddev-sans-first:30.92 20:42:13 INFO - PID 1668 | Values: 111.0 117.0 102.0 121.0 164.0 100.0 161.0 98.0 161.0 171.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:145.50 stddev:21.86 (15.0%) stddev-sans-first:23.18 20:42:13 INFO - PID 1668 | Values: 141.0 145.0 178.0 140.0 113.0 128.0 111.0 158.0 169.0 146.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:199.80 Median:200.50 stddev:2.74 (1.4%) stddev-sans-first:2.83 20:42:13 INFO - PID 1668 | Values: 198.0 201.0 204.0 204.0 200.0 198.0 197.0 199.0 201.0 196.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:101.30 Median:75.00 stddev:45.67 (60.9%) stddev-sans-first:46.72 20:42:13 INFO - PID 1668 | Values: 67.0 94.0 118.0 201.0 89.0 53.0 70.0 80.0 157.0 84.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:69.50 stddev:20.88 (30.0%) stddev-sans-first:21.51 20:42:13 INFO - PID 1668 | Values: 69.0 70.0 121.0 69.0 68.0 75.0 104.0 71.0 71.0 113.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:95.00 stddev:19.74 (20.8%) stddev-sans-first:20.38 20:42:13 INFO - PID 1668 | Values: 96.0 97.0 95.0 95.0 123.0 93.0 141.0 143.0 96.0 110.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:111.30 Median:98.00 stddev:21.47 (21.9%) stddev-sans-first:20.99 20:42:13 INFO - PID 1668 | Values: 135.0 155.0 98.0 98.0 99.0 98.0 99.0 98.0 99.0 134.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:92.00 stddev:1.56 (1.7%) stddev-sans-first:1.66 20:42:13 INFO - PID 1668 | Values: 92.0 92.0 91.0 92.0 92.0 91.0 89.0 95.0 93.0 93.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:61.50 stddev:14.41 (23.4%) stddev-sans-first:14.81 20:42:13 INFO - PID 1668 | Values: 50.0 49.0 48.0 51.0 57.0 66.0 78.0 78.0 81.0 43.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:61.50 stddev:13.14 (21.4%) stddev-sans-first:13.39 20:42:13 INFO - PID 1668 | Values: 55.0 62.0 80.0 89.0 82.0 61.0 61.0 55.0 53.0 56.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.50 stddev:0.79 (1.0%) stddev-sans-first:0.71 20:42:13 INFO - PID 1668 | Values: 80.0 82.0 81.0 81.0 82.0 82.0 80.0 81.0 82.0 81.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:143.00 stddev:9.45 (6.6%) stddev-sans-first:9.54 20:42:13 INFO - PID 1668 | Values: 136.0 137.0 140.0 153.0 142.0 137.0 144.0 146.0 141.0 167.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.50 Median:180.00 stddev:8.48 (4.7%) stddev-sans-first:8.75 20:42:13 INFO - PID 1668 | Values: 176.0 179.0 181.0 181.0 177.0 178.0 178.0 182.0 178.0 205.0 20:42:13 INFO - PID 1668 | 20:42:13 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:67.00 stddev:1.99 (3.0%) stddev-sans-first:1.74 20:42:13 INFO - PID 1668 | Values: 69.0 67.0 63.0 65.0 67.0 63.0 64.0 67.0 66.0 67.0 20:42:13 INFO - PID 1668 | -------- Summary: end -------- 20:42:13 INFO - PID 1668 | 20:42:13 INFO - TEST-INFO | 1668: exit 0 20:42:13 INFO - TEST-OK | kraken | took 89711ms 20:42:13 INFO - SUITE-END | took 1233s 20:42:14 INFO - Completed test suite (00:20:33) 20:42:14 INFO - javascript benchmark 20:42:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8409.844907604096, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3027.7729170312227, 3488.2325888304563, 3608.548931383577, 3632.8728494502698, 3657.5062366580514, 6301.054841773736, 6324.399182077706, 6339.651319177444, 6341.556091073627, 6348.460380867626, 6375.362463753622, 6379.489640828734, 6402.39177278159, 6410.871782564346, 6414.775482858101, 5573.888522229553, 5600.243967064455, 5604.719764011804, 5683.232763576905, 5727.656340619576, 4779.737114458703, 5637.577181711367, 5637.689927054011, 5657.8585535361735, 5727.742251598688, 5455.8363249102595, 5478.698671573062, 5535.889282214373, 5618.887622247573, 5673.375928647866, 5476.288082549243, 5516.14499752535, 5561.221428999943, 5565.109582466826, 5578.804741834057, 5497.780088796443, 5558.360788509332, 5596.188552659883, 5644.5484361251, 5647.717614119278, 6311.463525600329, 6332.588381755218, 6369.681515924185, 6373.044043393482, 6383.170187875593, 6888.965555172243, 6918.688659010357, 6932.48006399517, 6941.895871561931, 6947.756828511028, 6769.898451523231, 6874.175098988154, 6987.930120698778, 7013.33373329538, 7013.614251216181, 6394.26465956421, 6403.7438502459845, 6407.519436042222, 6433.999999999954, 6480.060391243338, 7109.111361079865, 7135.429165666734, 7150.213476517579, 7156.141263048364, 7180.425565954711, 6557.409833114947, 6648.8670226595195, 6653.2681405045405, 6810.489213308922, 6814.216365117975, 6808.523403361717, 6811.318868113248, 6898.482613803984, 6947.965260173768, 6964.860702786017, 6865.485088618374, 6879.277675844064, 6880.449564034865, 6896.93103068973, 6899.827504312432, 6694.631795251211, 6889.827754306182, 6916.135483064617, 6924.792256232321, 6944.583325000516, 4799.992001679617, 5394.0, 5424.620276580601, 5462.781488740486, 5503.147012213113, 4751.097291514601, 4760.571548560646, 4762.9523704763205, 4763.809447622057, 4782.904341913142, 5542.612017158841, 5858.2677165354335, 6337.0, 6436.420722135031, 6444.967775161188, 7012.859742805217, 7019.543729657556, 7037.366637002694, 7039.718411263492, 7059.5764254144915, 6412.5190610704385, 6443.645599491979, 6454.290028096905, 6479.384458476437, 6490.415862572391, 4772.928406073912, 4936.654434189572, 5939.227900372924, 6045.123457098696, 6197.597156184834, 6930.168379794457, 7035.155781306276, 7056.682449289897, 7109.644517774194, 7112.715491380493, 5793.9420605795085, 6888.931110688829, 6955.6869940851775, 6985.196702379291, 7007.054047703495, 7132.8930066048015, 7140.107486563972, 7140.250273721393, 7160.319769621878, 7164.2119366868565, 6819.896946889055, 6835.624040677811, 6858.245592984668, 6859.416949559343, 6878.965605171942, 6783.321667833177, 6871.690773915086, 6874.587524748531, 6880.8967865483055, 7005.334493223136, 6687.899681504879, 6697.162854643169, 6720.428763555153, 6773.62745049027, 6795.830104247433, 6806.251312355538, 6810.8637827244165, 6818.727250909908, 6831.726730930906, 6886.552374095668, 6401.679916004275, 6589.934100658932, 6676.399124078856, 6683.030960510601, 6685.09751183584, 6587.374199451045, 6613.5370524062855, 6622.933770662425, 6631.171103612049, 6636.502262330248, 6607.405333520006, 6613.0, 6617.305182955721, 6618.933810662024, 6632.568883022588], "value": 6258.097759185022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.7085853083175, 687.6046273392799, 825.8761185822123, 848.7581039403769, 858.9012263589689, 37850.24299514011, 38740.41889371662, 38742.80628596857, 38821.80589097051, 38942.80528597353, 38411.69529828205, 38628.0, 39051.41422878652, 39152.02119947003, 39280.0, 8345.582720863948, 8435.70475033374, 8476.406651534395, 8482.151784821499, 8501.404901656855, 8175.672973081069, 8226.917730822704, 8325.542095184763, 8329.750107496784, 8335.54154521501, 8296.958515207445, 8318.251357377834, 8328.583570821465, 8341.040780310252, 8367.539785311805, 8286.792830179233, 8314.0, 8319.542425166614, 8320.08479067305, 8338.332933365316, 8149.0, 8212.219839115274, 8215.34277257818, 8225.259726624632, 8237.46456480327, 37158.628413715785, 37168.628313717054, 37261.44107838385, 37387.0, 37483.0, 3595.2449985502813, 3596.5863925648623, 3617.8914632561064, 3635.0, 3637.5453068366455, 30268.697313027027, 30463.39073218523, 30521.542176867366, 30601.38797224043, 30628.387432251453, 3031.6058912341255, 3141.858616362269, 3173.8571764270664, 3177.126290270171, 3192.680731926812, 260354.0, 264732.0, 264997.3500265011, 319063.0, 319306.80693193, 47030.059398811834, 47986.520134798906, 48219.51780482221, 48497.0, 48566.27150592744, 49652.0, 49985.75007124941, 50023.74988125109, 50027.99944001099, 50028.249576256385, 15293.923530382277, 15354.0, 15377.384904603914, 15425.922870385577, 15444.15057171844, 12628.368581570885, 12825.29460879642, 12838.743225135446, 12855.742885142432, 12856.164349317265, 12120.575779847662, 12166.57416990401, 12231.388430578614, 12237.93881030607, 12326.383680815923, 5115.0, 5179.430262671103, 5181.81863634771, 5191.117510023305, 5209.218617207373, 991.3803872579638, 992.7418871093568, 1000.4197565412044, 1062.2086545523634, 1070.9143268538498, 17965.820341796676, 22692.0, 22840.428989275402, 23063.654045189334, 24226.42528235659, 1094.9835752463719, 1106.30134535389, 1106.9335839849634, 1152.4525850221078, 1178.0810967445407, 11189.888101119048, 11655.475503602358, 11861.11041671878, 11925.224860384047, 12271.877281227253, 352382.0, 353034.0, 353675.0, 354582.0, 354933.0, 53852.65368365744, 53996.46003539993, 54112.729436352565, 54231.0, 54484.09305674282, 1497.3561368611383, 1504.6689728259764, 1528.9694206115814, 1534.12554843738, 1536.0322996512125, 21867.965131846548, 22288.888555557118, 22481.763503007493, 22603.43491412728, 23987.28038158858, 32268.677313226566, 37446.06384840401, 37516.81241593775, 37516.81241593884, 37656.81171594124, 294901.0, 295605.0, 295720.0, 295882.0, 296382.51808740816, 32234.35531289314, 32997.35013249376, 33018.33963320768, 34323.0, 34366.65633343734, 35869.1032724184, 42932.35601466043, 44503.55496444994, 44576.55423445854, 44587.77706111449, 27321.0, 28191.683453381713, 28315.858420707766, 29207.415851683272, 30722.385552289277, 29279.712303317017, 30243.69756302497, 30771.846140769154, 30841.69158308388, 30922.69077309198, 36290.4556431659, 36512.81743591265, 36595.268094637424, 36608.45087323639, 36742.63257367392, 28801.42397152087, 29044.85477572683, 29321.560176597795, 29565.408691825614, 31109.533357000117, 22495.437614059778, 24275.271741847773, 24344.26967190987, 24590.139345122836, 24677.62983555212], "value": 17281.222988141966, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.28731896538807, 504.93710738894623, 511.11321856578854, 514.9433562308144, 535.8693157437804, 21886.89056554715, 21918.78081219187, 22383.0, 22983.77016229839, 23064.0, 23296.767032329673, 23367.532649347002, 23460.648090278617, 23554.411139721517, 23642.88178559109, 6301.6849157542165, 6479.8056058318325, 6501.934980650204, 6503.512236582252, 6510.121133646944, 6373.362663733636, 6429.774957876475, 6483.0, 6497.545171837974, 6499.772507962222, 5888.911666325009, 6160.260768707762, 6185.59793613416, 6195.907061394083, 6210.65841378724, 6120.663363515027, 6136.263648362181, 6165.506759459255, 6168.167297414859, 6175.536834737368, 5606.495415412592, 5618.494335509783, 5652.293463317085, 5654.4062873398525, 5656.151577263402, 28575.714242857513, 28645.14064578045, 28806.279843004093, 29058.709412905813, 29115.126546203646, 2273.1362082408746, 2317.3974766560646, 2337.5324935012895, 2338.590746619335, 2339.8830058497006, 18905.33831315911, 19069.61860762777, 19091.713624295648, 19207.90396048025, 19224.80775192258, 2155.622766015941, 2165.393689766852, 2181.6618424144285, 2202.9118835246572, 2240.6526988316837, 128605.0, 169153.0, 169515.0, 192900.03549982302, 200981.0, 14062.648433789238, 14530.491432799801, 15046.54860354191, 15373.231338433034, 15506.689866202612, 20056.49858753543, 20222.797772022386, 20230.0, 20242.291519796738, 20275.898620506803, 9058.429066124287, 9233.0, 10319.74200644975, 10326.01902819231, 10348.637797677045, 8241.299489543337, 9397.389169704084, 9677.854832177523, 9776.0, 9799.97100304472, 8976.576324392378, 9075.13786190311, 9112.726618201465, 9121.407108537924, 9135.862962055575, 2659.8670066496597, 2676.4038882284153, 2680.8793604287853, 2681.927229108372, 2774.0845520978028, 893.5784025291404, 913.3104506097853, 934.3039435620507, 954.4321128928276, 990.6879333010079, 19979.800201998085, 20322.390328290174, 20467.07898144587, 20990.895045524674, 21024.15903363879, 970.8106919150839, 980.1374790184592, 986.8421052631608, 1031.4430207687915, 1038.46000079958, 8582.356323275779, 9309.381315664567, 10644.733881653021, 11298.717532061764, 14435.927820361041, 585905.6213352187, 601160.0, 610002.0, 613916.930415354, 617483.0, 170056.0, 170330.14834925745, 171134.0, 173927.13036434737, 175555.0, 965.4786415335781, 1067.5516283160935, 1142.1319796954374, 1223.7980733178927, 1242.0250103668577, 21559.35321940344, 22264.666030009237, 23153.189638362575, 23167.420814479774, 23320.60076395422, 68136.97794533185, 69376.61246774935, 71425.6428717874, 72349.0, 72478.63760681162, 94361.58457623005, 94367.0, 97316.0, 98374.5081274589, 98832.50583747035, 11315.490802913724, 11425.257358271685, 11999.76000480003, 12084.335361555199, 12142.150049496804, 18379.0, 18562.0, 18582.535436614195, 18780.436586902415, 18901.621967560295, 13047.347632618521, 14399.78400324017, 14920.179390133648, 14989.250537472864, 15297.23513824282, 12915.22508649484, 13281.933590331986, 13675.521356752466, 13748.587542373745, 13898.367748672072, 15363.001404908646, 15448.922755386151, 15515.301811418287, 15660.68678626444, 15678.529644110695, 9752.0, 11806.881931180578, 11845.23006004607, 11854.229475084092, 11873.465694043616, 14386.992910496165, 14959.77560336574, 15030.0, 15171.089734615958, 15300.693986120437], "value": 11759.805156514194, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.2016689152056, 1234.5555599984004, 1439.7689975071314, 1439.8128243328358, 1449.7028109237608, 13589.93205033976, 13988.0, 14057.086289391182, 14154.221517816515, 14179.716405671881, 10835.620753273637, 14008.089474184171, 14014.22921739304, 14029.0, 14052.227127507984, 2788.6374771279757, 2817.197098826835, 2834.078924349584, 2837.1488553433887, 2837.6594808622995, 2784.5683918992486, 2819.238805522508, 2823.887044518217, 2824.844633545154, 2827.307309709124, 2305.873176975266, 2767.4465106978582, 2817.5632776919506, 2821.083147976916, 2831.377097038648, 2746.5880117982265, 2774.0290898185676, 2775.139706690922, 2775.653043369589, 2781.193453898367, 2638.22172459125, 2746.945061098787, 2754.724527547229, 2754.986225068882, 2768.321761168527, 13504.594862154152, 13594.864051359458, 13595.116317439335, 13631.659208519768, 13645.658858528615, 7509.662065207079, 7798.25916537928, 7808.64861081247, 7815.960920195363, 7835.451518393715, 3774.9811250943844, 4831.275308703723, 4919.60547222419, 4932.926006109876, 4945.629077819178, 1388.3960477192352, 1397.8532254113375, 1403.943842246309, 1423.5515812519127, 1429.0639631041581, 19110.426687199408, 19636.50908727279, 20505.38483845487, 20508.077136528744, 20631.381058568117, 6064.241969753781, 6730.091437655951, 6921.6193109379465, 6938.167419909643, 6997.615131167835, 14442.855571444152, 14515.983881128219, 14528.782068268985, 14552.63618409527, 14598.781018284733, 7204.9639751800905, 7553.660085296175, 7563.357114645317, 7581.0, 7586.089669239726, 6837.589744615339, 7047.823804404931, 7087.0, 7109.8933515997305, 7143.24995875436, 5293.682379057269, 5318.361796584357, 5385.299911011622, 5416.187571864252, 5430.619856609999, 1521.6576270339085, 1540.2067935013476, 1542.4986879264284, 1554.759012353087, 1564.7965764450548, 816.4611356504679, 843.6878355008686, 853.7865533616596, 861.1603686405705, 874.903760586335, 7197.0, 7251.419886409075, 7427.18300986891, 7508.511946723445, 7545.283198096172, 591.4410881716823, 677.861038487109, 702.153904545022, 702.5714314268292, 721.5346101764437, 4007.73949693269, 4449.7997590108525, 4987.276844857475, 5063.443021267658, 5079.6698214615935, 18353.81646183521, 18865.339713109974, 19017.61964760697, 19021.14404851785, 19091.61816763657, 15306.770398444032, 18269.269229230682, 18348.724769128472, 18927.52681182954, 19004.61990760177, 9472.631842039491, 9738.707838764849, 9829.0, 9854.852177217348, 10043.045910638331, 7750.612469376621, 7876.921230787619, 7896.447248692536, 7910.841783164189, 8271.379646526415, 16532.83467165313, 17169.828301717323, 17257.0508622027, 17272.395466158625, 17273.91363043177, 15553.68892622164, 15599.688006239585, 16784.496465106065, 17059.65880682404, 18056.36802711951, 9788.951055244679, 9912.860021097666, 9971.252156088178, 9974.40153590787, 10012.249081318785, 12554.748905021666, 12679.302638354979, 12700.555480558136, 12701.93649031749, 12705.0, 10065.345752526062, 10180.949095254773, 10335.000697503072, 10497.240318719658, 10497.895021049997, 9978.451185184884, 10003.449810260505, 10092.444915529422, 10335.586576536854, 10400.531976060944, 9226.538673066454, 9472.242220622471, 9502.287328450255, 9572.90427095748, 9591.136797688241, 6838.316168383122, 6883.8967415487805, 6959.446348877645, 7117.3238542338395, 7197.46019048563, 7015.754448594274, 7095.716171353088, 7280.745173918887, 7326.157491888501, 7375.631218438949], "value": 6139.970533739772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.6942071129498, 438.1827890983313, 462.13119335648986, 468.57359802579657, 472.06295503425696, 6550.377714117157, 6561.77033803817, 6572.934270657298, 6621.443960669239, 6639.302873198317, 5559.8888022239535, 6059.909331280701, 6154.323024467305, 6409.718056388718, 6456.7094480748365, 4786.784594693229, 4797.424309082915, 4799.544043315879, 4800.207965685667, 4822.541858523435, 3969.5114332125454, 4781.354517140194, 4793.2810078488155, 4803.382156724593, 4812.831550895719, 3909.824057917386, 4551.611758413683, 4728.66899317048, 4743.454502732178, 4798.304245884362, 4695.741734204617, 4729.929051064237, 4749.525047495257, 4754.358161648168, 4770.61835053195, 4131.421600975866, 4151.546958564479, 4176.1230141670285, 4187.120704652027, 4189.282394218386, 5314.4056782965345, 6132.356102609207, 6137.754489820447, 6165.982612868871, 6214.502839772807, 5603.7478313475995, 5612.41069687681, 5615.5507559395555, 5640.464155905182, 5658.264425624642, 6815.965920170417, 6831.760888368933, 6834.658267086676, 6855.0, 6877.690503927335, 4779.569838714498, 4781.760911954389, 4794.760261986921, 4799.352087468182, 4804.735739534324, 7128.358447739676, 7166.570005799668, 7237.746678866214, 7245.529040612343, 7339.522931009467, 4674.929876051862, 4860.8819971406065, 4863.8540843774745, 4870.975645121823, 4893.584045356185, 4719.764011799396, 4937.1606826839525, 4938.678985865908, 4944.011197760462, 4972.676776009548, 5653.095504719226, 5665.603407761417, 5673.461021203062, 5674.574406919415, 5696.1455781633085, 6348.619082855043, 6868.111269400954, 6876.071730316343, 6878.0, 6909.7581584644295, 1416.872481476672, 1632.379469082376, 1677.379369633219, 1678.5551828765388, 1686.7638530605725, 1052.9315594486486, 1113.9164562657834, 1135.761490087091, 1143.4111432612212, 1153.2734377342222, 6209.6895155242055, 6323.146375239376, 6405.19935008124, 6412.583182093149, 6419.143481150936, 5862.912056319158, 7095.50331476802, 7153.0, 7176.246494118147, 7201.0, 6046.395360463918, 6332.467013194759, 6332.651704156316, 6336.271328797156, 6385.010323399881, 3260.6087269527334, 3673.504076949631, 3681.9815900920325, 3708.7589306694977, 3733.701303895682, 6352.7141278642575, 7299.635018249066, 7317.0487836580905, 7342.70629174827, 7343.816404589821, 7254.238304977901, 7273.0, 7322.912969803687, 7332.30343117403, 7344.0, 6900.6204658744255, 7175.569465832067, 7296.16094149169, 7296.489245752747, 7326.706931722778, 7065.894011589728, 7085.574865508086, 7089.61007144612, 7090.149182098181, 7102.14774227096, 5802.5783682997935, 6964.616946067812, 6967.616781077089, 6973.372396484341, 6978.685959131953, 6096.268447786117, 6753.189617245962, 6889.276625954403, 7095.574265544084, 7275.308845659654, 4265.2749032663905, 4511.639068874548, 4825.3485779420735, 4865.245886887538, 4874.293227481924, 7093.645317733989, 7106.822329441598, 7118.181409137808, 7128.251533589106, 7129.679164437509, 5180.48195180479, 5593.133064375028, 6830.521863469509, 6855.177378714586, 6881.4494840413645, 6239.970404883325, 6631.734730610721, 6660.93768735774, 6866.6566671667215, 6871.347222013901, 5260.815871444481, 5688.943110569007, 5894.705264736832, 6232.753449310066, 6724.007032685952, 6238.500919926304, 6296.968515157395, 6724.899126513008, 6799.320067993359, 6824.078749368771, 5528.612997090326, 5587.720613969204, 6410.006449000412, 6648.202215734143, 6681.899771503528], "value": 5042.406961018526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.225578932646, 833.8332333533294, 838.9370797190212, 869.9826003479931, 877.333226747672, 14279.928600356996, 17068.658626827455, 17244.96530208185, 17261.65476690467, 17271.39550115746, 15354.769678454804, 15700.21498925055, 15918.363265469396, 16255.349786008546, 16340.673186536293, 983.5819776594955, 1003.0471052500118, 1006.607423104986, 1006.8942761010098, 1007.7833265847842, 1007.8639383683183, 1013.8935411781767, 1014.2089170447026, 1015.7968406318765, 1017.3793985668736, 1054.6888667843004, 1058.30680904008, 1059.750958524746, 1060.2366296266675, 1060.6181774561153, 998.3710262534629, 1040.089921318846, 1040.750219947211, 1048.6434612231878, 1050.978980420395, 771.0208035794566, 772.1467778105169, 774.8062984253936, 774.8217909880759, 776.3827756933251, 17133.400330988356, 17512.12439378026, 17516.474505764847, 17700.734488982675, 17734.645307093913, 14350.856491435161, 17678.91160544202, 18014.36949706754, 18179.27282908682, 18322.35871744496, 14693.118412895154, 14922.626934326729, 14951.626209344744, 15099.849001509954, 15112.622184445478, 3622.5834029086727, 3625.6918161956264, 3634.7092232621326, 3646.5441819772527, 3647.74465787395, 93663.53168234116, 137209.0, 141371.29314353364, 160292.0, 163725.3627463722, 11437.828432573519, 11663.650090497298, 14295.142291462545, 14366.928165359106, 14429.639259018608, 26313.34216644561, 26613.20160395191, 26672.666366681588, 26828.061017864282, 26849.926002960052, 7767.339776119094, 7806.1413244543055, 7922.683092676343, 7972.3622110231045, 8061.838763224702, 4053.412255222976, 4136.834526618962, 4166.0834616384345, 4176.644985176234, 4178.749275043507, 4735.981763920705, 4767.451743049524, 4804.711717296974, 4832.975835120872, 4838.782254798542, 880.2869675562814, 1041.807265655856, 1048.114343379843, 1058.0847566854616, 1060.8408738689259, 629.3109045595066, 637.4326849104301, 637.4517914593446, 649.6621756686496, 671.3554987212233, 11339.773204535863, 11557.479913403917, 14189.148651080968, 14284.642883927778, 14286.428542858377, 611.764236242796, 639.5011890725242, 661.8874791285493, 667.6027763480721, 674.1169068520254, 4945.282933974553, 5622.240997465372, 5718.485336319751, 5805.419458054161, 6027.60820546663, 131584.0, 138877.0, 139231.0, 140531.0, 144790.0, 118010.40994794971, 120380.0, 120782.396088019, 125432.1185182223, 127546.0, 92776.53611732033, 107705.4614726937, 108613.0, 111168.0, 111614.0, 12635.0, 12663.620091397273, 12667.0, 12709.237445753284, 13198.80201796993, 33554.0, 43926.34110488281, 45052.549474504835, 46784.298235527174, 47340.999999998625, 83499.5825020871, 85697.57151214454, 86032.13967860241, 89300.6604900914, 89682.55158724164, 17634.294628214728, 18370.35703750362, 18370.540736481697, 18435.0, 18483.90758046201, 18251.0, 18614.53463663419, 18943.526411839262, 18985.810141898404, 19177.712334315274, 17149.056801876017, 17489.82510174882, 17745.201465933806, 18229.817701822813, 18370.908145459187, 17457.912710436365, 17995.2801887923, 18230.635387292445, 18354.541136471697, 18456.0, 16668.16659167061, 17428.128593570524, 17564.912175439553, 17922.0, 18026.63946721032, 9373.66271153488, 11693.356865372487, 11764.882351176379, 12278.570250041206, 12314.384280785745, 13275.0, 13893.72212555723, 14069.507567235507, 14489.0, 14514.85485145135], "value": 8984.211988019606, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 117.0, 102.0, 121.0, 164.0, 100.0, 161.0, 98.0, 161.0, 171.0], "value": 130.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 145.0, 178.0, 140.0, 113.0, 128.0, 111.0, 158.0, 169.0, 146.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 204.0, 204.0, 200.0, 198.0, 197.0, 199.0, 201.0, 196.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 94.0, 118.0, 201.0, 89.0, 53.0, 70.0, 80.0, 157.0, 84.0], "value": 101.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 121.0, 69.0, 68.0, 75.0, 104.0, 71.0, 71.0, 113.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 95.0, 123.0, 93.0, 141.0, 143.0, 96.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 155.0, 98.0, 98.0, 99.0, 98.0, 99.0, 98.0, 99.0, 134.0], "value": 111.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 91.0, 92.0, 92.0, 91.0, 89.0, 95.0, 93.0, 93.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 48.0, 51.0, 57.0, 66.0, 78.0, 78.0, 81.0, 43.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 80.0, 89.0, 82.0, 61.0, 61.0, 55.0, 53.0, 56.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 81.0, 81.0, 82.0, 82.0, 80.0, 81.0, 82.0, 81.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 140.0, 153.0, 142.0, 137.0, 144.0, 146.0, 141.0, 167.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 179.0, 181.0, 181.0, 177.0, 178.0, 178.0, 182.0, 178.0, 205.0], "value": 181.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 63.0, 65.0, 67.0, 63.0, 64.0, 67.0, 66.0, 67.0], "value": 65.8, "unit": "score"}]}]} 20:42:14 INFO - Return code: 0 20:42:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:42:14 INFO - # TBPL SUCCESS # 20:42:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:42:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:42:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:42:14 INFO - 2017-06-14 20:42:14.579 screenresolution[1676:11162] starting screenresolution argv=screenresolution get 20:42:14 INFO - 2017-06-14 20:42:14.581 screenresolution[1676:11162] Display 0: 1600x1200x32@60 20:42:14 INFO - 2017-06-14 20:42:14.587 screenresolution[1677:11165] starting screenresolution argv=screenresolution list 20:42:14 INFO - Available Modes on Display 0 20:42:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:42:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:42:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:42:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:42:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:42:14 INFO - Intel Iris: 20:42:14 INFO - Chipset Model: Intel Iris 20:42:14 INFO - Type: GPU 20:42:14 INFO - Bus: Built-In 20:42:14 INFO - VRAM (Dynamic, Max): 1536 MB 20:42:14 INFO - Vendor: Intel (0x8086) 20:42:14 INFO - Device ID: 0x0a2e 20:42:14 INFO - Revision ID: 0x0009 20:42:14 INFO - Displays: 20:42:14 INFO - Display: 20:42:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:42:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:42:14 INFO - Display Serial Number: 1600x1200 60 20:42:14 INFO - Main Display: Yes 20:42:14 INFO - Mirror: Off 20:42:14 INFO - Online: Yes 20:42:14 INFO - Rotation: Supported 20:42:14 INFO - Return code: 0 20:42:14 INFO - Running post-action listener: _resource_record_post_action 20:42:14 INFO - [mozharness: 2017-06-15 03:42:14.822139Z] Finished run-tests step (success) 20:42:14 INFO - Running post-run listener: _resource_record_post_run 20:42:14 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40516096; Write bytes: 564368384; Read time: 264; Write time: 1759 20:42:14 INFO - TinderboxPrint: CPU usage
53.2% 20:42:14 INFO - TinderboxPrint: I/O read bytes / time
40,516,096 / 264 20:42:14 INFO - TinderboxPrint: I/O write bytes / time
564,368,384 / 1,759 20:42:14 INFO - TinderboxPrint: CPU idle
2,350.3 (46.8%) 20:42:14 INFO - TinderboxPrint: CPU system
1,022.3 (20.3%) 20:42:14 INFO - TinderboxPrint: CPU user
1,653.1 (32.9%) 20:42:14 INFO - TinderboxPrint: Swap in / out
283,713,536 / 0 20:42:14 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 228559360; Write bytes: 215580672; Read time: 16783; Write time: 464 20:42:14 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 20:42:14 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 34222592; Write bytes: 315550720; Read time: 210; Write time: 1198 20:42:15 INFO - Running post-run listener: _upload_blobber_files 20:42:15 INFO - Blob upload gear active. 20:42:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42:15 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 20:42:15 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'] 20:42:15 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 20:42:15 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:42:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:42:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:16 INFO - (blobuploader) - INFO - Done attempting. 20:42:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:42:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:42:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:17 INFO - (blobuploader) - INFO - Done attempting. 20:42:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:42:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:42:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:18 INFO - (blobuploader) - INFO - Done attempting. 20:42:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:42:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:20 INFO - (blobuploader) - INFO - Done attempting. 20:42:20 INFO - (blobuploader) - INFO - Iteration through files over. 20:42:20 INFO - Return code: 0 20:42:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:42:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:42:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7c94649d266d2acae369c345acb13e494e6e36a4d965981ab908f641992dde1d5d868212cace8b8e87af52688e7e4dcf9a52f2aca5c9d8b9338b46c53637e11d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/694b00ca1bd059e2f6624c4e73e75b377ddbfcbc7a4fa51c251a0e84bee8759c1a96a68be1b2723d6819379ca6d8da689ee5d01b3a480eee59b93063d175cb2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c282772bf26cf828745e07533ce459a32ae8f370ced9788eaf4e54d10fe7b2a4e75c12eebcdcf42295e49f36c4516a455b588dfb2855b1c03ac5f13e34c5a1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54c3cce0b6a5ff028e78e4569d4cd5726ca9e815266a3c2354b3713dcf43fe99202e122f71308e859dd47c9762a15988e0ed4756bcf0071312a48d80f1e41e1a"} 20:42:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:42:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:42:20 INFO - Contents: 20:42:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7c94649d266d2acae369c345acb13e494e6e36a4d965981ab908f641992dde1d5d868212cace8b8e87af52688e7e4dcf9a52f2aca5c9d8b9338b46c53637e11d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/694b00ca1bd059e2f6624c4e73e75b377ddbfcbc7a4fa51c251a0e84bee8759c1a96a68be1b2723d6819379ca6d8da689ee5d01b3a480eee59b93063d175cb2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c282772bf26cf828745e07533ce459a32ae8f370ced9788eaf4e54d10fe7b2a4e75c12eebcdcf42295e49f36c4516a455b588dfb2855b1c03ac5f13e34c5a1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54c3cce0b6a5ff028e78e4569d4cd5726ca9e815266a3c2354b3713dcf43fe99202e122f71308e859dd47c9762a15988e0ed4756bcf0071312a48d80f1e41e1a"} 20:42:20 INFO - Running post-run listener: copy_logs_to_upload_dir 20:42:20 INFO - Copying logs to upload dir... 20:42:20 INFO - mkdir: /builds/slave/test/build/upload/logs 20:42:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.335702 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-14 20:42:20.929982) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 20:42:20.933596) ========= 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.BpbKAeeUu3/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.INP0DctADJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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/7c94649d266d2acae369c345acb13e494e6e36a4d965981ab908f641992dde1d5d868212cace8b8e87af52688e7e4dcf9a52f2aca5c9d8b9338b46c53637e11d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/694b00ca1bd059e2f6624c4e73e75b377ddbfcbc7a4fa51c251a0e84bee8759c1a96a68be1b2723d6819379ca6d8da689ee5d01b3a480eee59b93063d175cb2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c282772bf26cf828745e07533ce459a32ae8f370ced9788eaf4e54d10fe7b2a4e75c12eebcdcf42295e49f36c4516a455b588dfb2855b1c03ac5f13e34c5a1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54c3cce0b6a5ff028e78e4569d4cd5726ca9e815266a3c2354b3713dcf43fe99202e122f71308e859dd47c9762a15988e0ed4756bcf0071312a48d80f1e41e1a"} build_url:https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010964 build_url: 'https://queue.taskcluster.net/v1/task/N3unnXq5T0O1L0MRFbEbVw/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/7c94649d266d2acae369c345acb13e494e6e36a4d965981ab908f641992dde1d5d868212cace8b8e87af52688e7e4dcf9a52f2aca5c9d8b9338b46c53637e11d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/694b00ca1bd059e2f6624c4e73e75b377ddbfcbc7a4fa51c251a0e84bee8759c1a96a68be1b2723d6819379ca6d8da689ee5d01b3a480eee59b93063d175cb2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c282772bf26cf828745e07533ce459a32ae8f370ced9788eaf4e54d10fe7b2a4e75c12eebcdcf42295e49f36c4516a455b588dfb2855b1c03ac5f13e34c5a1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54c3cce0b6a5ff028e78e4569d4cd5726ca9e815266a3c2354b3713dcf43fe99202e122f71308e859dd47c9762a15988e0ed4756bcf0071312a48d80f1e41e1a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 20:42:20.964704) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 20:42:20.964985) ========= 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.BpbKAeeUu3/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.INP0DctADJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.005118 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 20:42:21.012770) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 20:42:21.013055) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 20:42:21.013342) ========= ========= Total master_lag: 0.16 =========