builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0015 starttime: 1497392853.32 results: success (0) buildid: 20170613142334 builduid: 3b603cfe98b047f0a20beb83c7f14b0e revision: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.319035) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.319523) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.319798) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.344744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.345139) ========= 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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-13 15:27:33-- 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% 9.31M=0.001s 2017-06-13 15:27:33 (9.31 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103389 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.471751) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.472110) ========= 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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.036798 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:33.530325) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 15:27:33.530689) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 --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 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HRE4zpbwL5/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.gcDF2MWpjt/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-13 15:27:33,638 truncating revision to first 12 chars 2017-06-13 15:27:33,639 Setting DEBUG logging. 2017-06-13 15:27:33,639 attempt 1/10 2017-06-13 15:27:33,639 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0a00302fa5cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 15:27:35,386 unpacking tar archive at: mozilla-beta-0a00302fa5cc/testing/mozharness/ program finished with exit code 0 elapsedTime=2.027626 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 15:27:35.582595) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:35.583138) ========= script_repo_revision: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:35.583758) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:35.584237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 15:27:35.604408) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-13 15:27:35.605027) ========= /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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:27:35 INFO - MultiFileLogger online at 20170613 15:27:35 in /builds/slave/test 15:27:35 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 15:27:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:27:35 INFO - {'append_to_log': False, 15:27:35 INFO - 'base_work_dir': '/builds/slave/test', 15:27:35 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:27:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:27:35 INFO - 'branch': 'Mozilla-Beta', 15:27:35 INFO - 'buildbot_json_path': 'buildprops.json', 15:27:35 INFO - 'config_files': ('talos/mac_config.py',), 15:27:35 INFO - 'default_actions': ('clobber', 15:27:35 INFO - 'read-buildbot-config', 15:27:35 INFO - 'download-and-extract', 15:27:35 INFO - 'populate-webroot', 15:27:35 INFO - 'create-virtualenv', 15:27:35 INFO - 'install', 15:27:35 INFO - 'setup-mitmproxy', 15:27:35 INFO - 'run-tests'), 15:27:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:27:35 INFO - 'download_minidump_stackwalk': True, 15:27:35 INFO - 'download_symbols': 'ondemand', 15:27:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:27:35 INFO - 'tooltool.py': '/tools/tooltool.py', 15:27:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:27:35 INFO - '/tools/misc-python/virtualenv.py')}, 15:27:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:27:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:27:35 INFO - 'gecko_profile': False, 15:27:35 INFO - 'gecko_profile_interval': 0, 15:27:35 INFO - 'installer_path': 'installer.exe', 15:27:35 INFO - 'log_level': 'info', 15:27:35 INFO - 'log_name': 'talos', 15:27:35 INFO - 'log_to_console': True, 15:27:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:27:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:27:35 INFO - 'opt_config_files': (), 15:27:35 INFO - 'pip_index': False, 15:27:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:27:35 INFO - 'cmd': ('bash', 15:27:35 INFO - '-c', 15:27:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:27:35 INFO - 'enabled': True, 15:27:35 INFO - 'halt_on_failure': False, 15:27:35 INFO - 'name': 'check_screen_resolution'},), 15:27:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:27:35 INFO - 'cmd': ('bash', 15:27:35 INFO - '-c', 15:27:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:27:35 INFO - 'enabled': True, 15:27:35 INFO - 'halt_on_failure': False, 15:27:35 INFO - 'name': 'check_screen_resolution'},), 15:27:35 INFO - 'run_cmd_checks_enabled': True, 15:27:35 INFO - 'suite': 'dromaeojs-e10s', 15:27:35 INFO - 'system_bits': '32', 15:27:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:27:35 INFO - 'title': 't-yosemite-r7-0015', 15:27:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:27:35 INFO - 'use_talos_json': True, 15:27:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:27:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:27:35 INFO - 'work_dir': 'build'} 15:27:35 INFO - [mozharness: 2017-06-13 22:27:35.824367Z] Running clobber step. 15:27:35 INFO - Running pre-action listener: _resource_record_pre_action 15:27:35 INFO - Running main action method: clobber 15:27:35 INFO - rmtree: /builds/slave/test/build 15:27:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:27:38 INFO - Running post-action listener: _resource_record_post_action 15:27:38 INFO - [mozharness: 2017-06-13 22:27:38.279938Z] Finished clobber step (success) 15:27:38 INFO - [mozharness: 2017-06-13 22:27:38.280103Z] Running read-buildbot-config step. 15:27:38 INFO - Running pre-action listener: _resource_record_pre_action 15:27:38 INFO - Running main action method: read_buildbot_config 15:27:38 INFO - Using buildbot properties: 15:27:38 INFO - { 15:27:38 INFO - "project": "", 15:27:38 INFO - "product": "firefox", 15:27:38 INFO - "script_repo_revision": "production", 15:27:38 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 15:27:38 INFO - "repository": "", 15:27:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 15:27:38 INFO - "buildid": "20170613142334", 15:27:38 INFO - "pgo_build": "False", 15:27:38 INFO - "basedir": "/builds/slave/test", 15:27:38 INFO - "buildnumber": 4, 15:27:38 INFO - "slavename": "t-yosemite-r7-0015", 15:27:38 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:27:38 INFO - "platform": "yosemite_r7_devedition", 15:27:38 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 15:27:38 INFO - "branch": "mozilla-beta", 15:27:38 INFO - "repo_path": "releases/mozilla-beta", 15:27:38 INFO - "revision": "0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416", 15:27:38 INFO - "stage_platform": "macosx64-devedition", 15:27:38 INFO - "builduid": "3b603cfe98b047f0a20beb83c7f14b0e", 15:27:38 INFO - "slavebuilddir": "test" 15:27:38 INFO - } 15:27:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 15:27:38 INFO - Running post-action listener: _resource_record_post_action 15:27:38 INFO - [mozharness: 2017-06-13 22:27:38.284127Z] Finished read-buildbot-config step (success) 15:27:38 INFO - [mozharness: 2017-06-13 22:27:38.284267Z] Running download-and-extract step. 15:27:38 INFO - Running pre-action listener: _resource_record_pre_action 15:27:38 INFO - Running main action method: download_and_extract 15:27:38 INFO - mkdir: /builds/slave/test/build/tests 15:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:38 INFO - trying https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 15:27:38 INFO - Downloading https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/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 15:27:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/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 15:27:38 INFO - Downloaded 1290 bytes. 15:27:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 15:27:38 INFO - Using the following test package requirements: 15:27:38 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 15:27:38 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:27:38 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 15:27:38 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 15:27:38 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:27:38 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 15:27:38 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:27:38 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 15:27:38 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 15:27:38 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 15:27:38 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:27:38 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 15:27:38 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 15:27:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:27:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:27:38 INFO - Fetch https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:27:40 INFO - Content-Length response header: 59545533 15:27:40 INFO - Bytes received: 59545533 15:27:59 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 15:27:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:27:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:27:59 INFO - Fetch https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:28:01 INFO - Content-Length response header: 59545533 15:28:01 INFO - Bytes received: 59545533 15:28:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 15:28:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 15:28:18 INFO - Fetch https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 15:28:19 INFO - Content-Length response header: 14450018 15:28:19 INFO - Bytes received: 14450018 15:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:19 INFO - trying https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:28:19 INFO - Downloading https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 15:28:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/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 15:28:21 INFO - Downloaded 66876876 bytes. 15:28:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:28:21 INFO - mkdir: /builds/slave/test/properties 15:28:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:28:21 INFO - Writing to file /builds/slave/test/properties/build_url 15:28:21 INFO - Contents: 15:28:21 INFO - build_url:https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:28:22 INFO - Running post-action listener: _resource_record_post_action 15:28:22 INFO - Running post-action listener: set_extra_try_arguments 15:28:22 INFO - [mozharness: 2017-06-13 22:28:22.089175Z] Finished download-and-extract step (success) 15:28:22 INFO - [mozharness: 2017-06-13 22:28:22.089354Z] Running populate-webroot step. 15:28:22 INFO - Running pre-action listener: _resource_record_pre_action 15:28:22 INFO - Running main action method: populate_webroot 15:28:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:28:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:28:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:28:22 INFO - '--tppagecycles', 15:28:22 INFO - '7'], 15:28:22 INFO - 'tests': ['tsvgm']}}, 15:28:22 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:28:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:28:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:28:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:28:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:28:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['damp', 'tps']}, 15:28:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'tests': ['damp', 'tps']}, 15:28:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['dromaeo_dom']}, 15:28:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:28:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:28:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:28:22 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:28:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['a11yr', 15:28:22 INFO - 'ts_paint', 15:28:22 INFO - 'tpaint', 15:28:22 INFO - 'sessionrestore', 15:28:22 INFO - 'sessionrestore_no_auto_restore', 15:28:22 INFO - 'tabpaint']}, 15:28:22 INFO - 'other-e10s': {'tests': ['a11yr', 15:28:22 INFO - 'ts_paint', 15:28:22 INFO - 'tpaint', 15:28:22 INFO - 'sessionrestore', 15:28:22 INFO - 'sessionrestore_no_auto_restore', 15:28:22 INFO - 'tabpaint']}, 15:28:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:28:22 INFO - 'ts_paint', 15:28:22 INFO - 'tpaint', 15:28:22 INFO - 'sessionrestore', 15:28:22 INFO - 'sessionrestore_no_auto_restore', 15:28:22 INFO - 'tabpaint']}, 15:28:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:28:22 INFO - 'ts_paint', 15:28:22 INFO - 'tpaint', 15:28:22 INFO - 'sessionrestore', 15:28:22 INFO - 'sessionrestore_no_auto_restore', 15:28:22 INFO - 'tabpaint']}, 15:28:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['a11yr', 15:28:22 INFO - 'ts_paint', 15:28:22 INFO - 'tpaint', 15:28:22 INFO - 'sessionrestore', 15:28:22 INFO - 'sessionrestore_no_auto_restore', 15:28:22 INFO - 'tabpaint']}, 15:28:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['a11yr', 15:28:22 INFO - 'ts_paint', 15:28:22 INFO - 'tpaint', 15:28:22 INFO - 'sessionrestore', 15:28:22 INFO - 'sessionrestore_no_auto_restore', 15:28:22 INFO - 'tabpaint']}, 15:28:22 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:28:22 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:28:22 INFO - 'bloom_basic_ref']}, 15:28:22 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:28:22 INFO - 'talos_options': ['--mitmproxy', 15:28:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:28:22 INFO - '--firstNonBlankPaint'], 15:28:22 INFO - 'tests': ['quantum_pageload_google', 15:28:22 INFO - 'quantum_pageload_youtube', 15:28:22 INFO - 'quantum_pageload_amazon', 15:28:22 INFO - 'quantum_pageload_facebook']}, 15:28:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['tsvgx', 15:28:22 INFO - 'tsvgr_opacity', 15:28:22 INFO - 'tart', 15:28:22 INFO - 'tscrollx', 15:28:22 INFO - 'cart', 15:28:22 INFO - 'tsvg_static']}, 15:28:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:28:22 INFO - 'tsvgr_opacity', 15:28:22 INFO - 'tart', 15:28:22 INFO - 'tscrollx', 15:28:22 INFO - 'cart', 15:28:22 INFO - 'tsvg_static']}, 15:28:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'talos_options': ['--disable-e10s'], 15:28:22 INFO - 'tests': ['tp5o']}, 15:28:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:28:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'talos_options': ['--disable-e10s', 15:28:22 INFO - '--xperf_path', 15:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:28:22 INFO - 'tests': ['tp5n']}, 15:28:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:28:22 INFO - 'talos_options': ['--xperf_path', 15:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:28:22 INFO - 'tests': ['tp5n']}}, 15:28:22 INFO - 'talos.zip': {'path': '', 15:28:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:28:22 INFO - Running post-action listener: _resource_record_post_action 15:28:22 INFO - [mozharness: 2017-06-13 22:28:22.109676Z] Finished populate-webroot step (success) 15:28:22 INFO - [mozharness: 2017-06-13 22:28:22.109818Z] Running create-virtualenv step. 15:28:22 INFO - Running pre-action listener: _resource_record_pre_action 15:28:22 INFO - Running main action method: create_virtualenv 15:28:22 INFO - Creating virtualenv /builds/slave/test/build/venv 15:28:22 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 15:28:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:28:22 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:28:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:28:22 INFO - Using real prefix '/tools/python27' 15:28:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:28:23 INFO - Installing distribute.............................................................................................................................................................................................done. 15:28:25 INFO - Installing pip.................done. 15:28:25 INFO - Return code: 0 15:28:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:28:26 INFO - Reading from file tmpfile_stdout 15:28:26 INFO - Output received: 15:28:26 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) 15:28:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:28:26 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')]} 15:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:26 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')]} 15:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:26 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:26 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 15:28:26 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 15:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:26 INFO - 'HOME': '/Users/cltbld', 15:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:26 INFO - 'LOGNAME': 'cltbld', 15:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:26 INFO - 'MOZ_NO_REMOTE': '1', 15:28:26 INFO - 'NO_EM_RESTART': '1', 15:28:26 INFO - 'PAGER': '/bin/cat', 15:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:26 INFO - 'PWD': '/builds/slave/test', 15:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:26 INFO - 'SHELL': '/bin/bash', 15:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:26 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:26 INFO - 'USER': 'cltbld', 15:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:26 INFO - 'XPC_FLAGS': '0x0', 15:28:26 INFO - 'XPC_SERVICE_NAME': '0', 15:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:26 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 15:28:26 INFO - Cleaning up... 15:28:26 INFO - Return code: 0 15:28:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:28:26 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')]} 15:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:26 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')]} 15:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:26 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:26 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 15:28:26 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 15:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:26 INFO - 'HOME': '/Users/cltbld', 15:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:26 INFO - 'LOGNAME': 'cltbld', 15:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:26 INFO - 'MOZ_NO_REMOTE': '1', 15:28:26 INFO - 'NO_EM_RESTART': '1', 15:28:26 INFO - 'PAGER': '/bin/cat', 15:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:26 INFO - 'PWD': '/builds/slave/test', 15:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:26 INFO - 'SHELL': '/bin/bash', 15:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:26 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:26 INFO - 'USER': 'cltbld', 15:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:26 INFO - 'XPC_FLAGS': '0x0', 15:28:26 INFO - 'XPC_SERVICE_NAME': '0', 15:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:26 INFO - Downloading/unpacking psutil>=3.1.1 15:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:28:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:28:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:28:28 INFO - Installing collected packages: psutil 15:28:28 INFO - Running setup.py install for psutil 15:28:28 INFO - building 'psutil._psutil_osx' extension 15:28:28 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 15:28:29 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 15:28:29 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 15:28:29 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 15:28:29 INFO - building 'psutil._psutil_posix' extension 15:28:29 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 15:28:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:28:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:28:29 INFO - ^ 15:28:29 INFO - 1 warning generated. 15:28:29 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 15:28:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:28:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:28:29 INFO - Successfully installed psutil 15:28:29 INFO - Cleaning up... 15:28:29 INFO - Return code: 0 15:28:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:30 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')]} 15:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:30 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:30 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 15:28:30 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 15:28:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:30 INFO - 'HOME': '/Users/cltbld', 15:28:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:30 INFO - 'LOGNAME': 'cltbld', 15:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:30 INFO - 'MOZ_NO_REMOTE': '1', 15:28:30 INFO - 'NO_EM_RESTART': '1', 15:28:30 INFO - 'PAGER': '/bin/cat', 15:28:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:30 INFO - 'PWD': '/builds/slave/test', 15:28:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:30 INFO - 'SHELL': '/bin/bash', 15:28:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:30 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:30 INFO - 'USER': 'cltbld', 15:28:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:30 INFO - 'XPC_FLAGS': '0x0', 15:28:30 INFO - 'XPC_SERVICE_NAME': '0', 15:28:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:28:30 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 15:28:30 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 15:28:30 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 15:28:30 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 15:28:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:28:32 INFO - Installing collected packages: mozsystemmonitor 15:28:32 INFO - Running setup.py install for mozsystemmonitor 15:28:32 INFO - Successfully installed mozsystemmonitor 15:28:32 INFO - Cleaning up... 15:28:32 INFO - Return code: 0 15:28:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:33 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')]} 15:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:33 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:33 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 15:28:33 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 15:28:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:33 INFO - 'HOME': '/Users/cltbld', 15:28:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:33 INFO - 'LOGNAME': 'cltbld', 15:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:33 INFO - 'MOZ_NO_REMOTE': '1', 15:28:33 INFO - 'NO_EM_RESTART': '1', 15:28:33 INFO - 'PAGER': '/bin/cat', 15:28:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:33 INFO - 'PWD': '/builds/slave/test', 15:28:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:33 INFO - 'SHELL': '/bin/bash', 15:28:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:33 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:33 INFO - 'USER': 'cltbld', 15:28:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:33 INFO - 'XPC_FLAGS': '0x0', 15:28:33 INFO - 'XPC_SERVICE_NAME': '0', 15:28:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:33 INFO - Downloading/unpacking jsonschema==2.5.1 15:28:33 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 15:28:33 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 15:28:33 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 15:28:33 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 15:28:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:28:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:35 INFO - Downloading functools32-3.2.3-2.tar.gz 15:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:28:35 INFO - warning: no files found matching '*.txt' 15:28:35 INFO - no previously-included directories found matching 'build' 15:28:35 INFO - no previously-included directories found matching 'dist' 15:28:35 INFO - no previously-included directories found matching '.git*' 15:28:35 INFO - Installing collected packages: jsonschema, functools32 15:28:35 INFO - Running setup.py install for functools32 15:28:35 INFO - warning: no files found matching '*.txt' 15:28:35 INFO - no previously-included directories found matching 'build' 15:28:35 INFO - no previously-included directories found matching 'dist' 15:28:35 INFO - no previously-included directories found matching '.git*' 15:28:35 INFO - Successfully installed jsonschema functools32 15:28:35 INFO - Cleaning up... 15:28:35 INFO - Return code: 0 15:28:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:28:35 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')]} 15:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:35 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')]} 15:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:35 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:35 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 15:28:35 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 15:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:35 INFO - 'HOME': '/Users/cltbld', 15:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:35 INFO - 'LOGNAME': 'cltbld', 15:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:35 INFO - 'MOZ_NO_REMOTE': '1', 15:28:35 INFO - 'NO_EM_RESTART': '1', 15:28:35 INFO - 'PAGER': '/bin/cat', 15:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:35 INFO - 'PWD': '/builds/slave/test', 15:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:35 INFO - 'SHELL': '/bin/bash', 15:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:35 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:35 INFO - 'USER': 'cltbld', 15:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:35 INFO - 'XPC_FLAGS': '0x0', 15:28:35 INFO - 'XPC_SERVICE_NAME': '0', 15:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:28:35 INFO - Cleaning up... 15:28:35 INFO - Return code: 0 15:28:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:28:35 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')]} 15:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:35 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')]} 15:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:35 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:35 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 15:28:35 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 15:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:35 INFO - 'HOME': '/Users/cltbld', 15:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:35 INFO - 'LOGNAME': 'cltbld', 15:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:35 INFO - 'MOZ_NO_REMOTE': '1', 15:28:35 INFO - 'NO_EM_RESTART': '1', 15:28:35 INFO - 'PAGER': '/bin/cat', 15:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:35 INFO - 'PWD': '/builds/slave/test', 15:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:35 INFO - 'SHELL': '/bin/bash', 15:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:35 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:35 INFO - 'USER': 'cltbld', 15:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:35 INFO - 'XPC_FLAGS': '0x0', 15:28:35 INFO - 'XPC_SERVICE_NAME': '0', 15:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:36 INFO - Downloading/unpacking blobuploader==1.2.4 15:28:36 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 15:28:36 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 15:28:36 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 15:28:36 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 15:28:37 INFO - Downloading blobuploader-1.2.4.tar.gz 15:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:28:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:28:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:28:38 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 15:28:38 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 15:28:38 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 15:28:38 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 15:28:38 INFO - Downloading docopt-0.6.1.tar.gz 15:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:28:38 INFO - Installing collected packages: blobuploader, requests, docopt 15:28:38 INFO - Running setup.py install for blobuploader 15:28:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:28:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:28:38 INFO - Running setup.py install for requests 15:28:39 INFO - Running setup.py install for docopt 15:28:39 INFO - Successfully installed blobuploader requests docopt 15:28:39 INFO - Cleaning up... 15:28:39 INFO - Return code: 0 15:28:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:39 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:39 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 15:28:39 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 15:28:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:39 INFO - 'HOME': '/Users/cltbld', 15:28:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:39 INFO - 'LOGNAME': 'cltbld', 15:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:39 INFO - 'MOZ_NO_REMOTE': '1', 15:28:39 INFO - 'NO_EM_RESTART': '1', 15:28:39 INFO - 'PAGER': '/bin/cat', 15:28:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:39 INFO - 'PWD': '/builds/slave/test', 15:28:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:39 INFO - 'SHELL': '/bin/bash', 15:28:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:39 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:39 INFO - 'USER': 'cltbld', 15:28:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:39 INFO - 'XPC_FLAGS': '0x0', 15:28:39 INFO - 'XPC_SERVICE_NAME': '0', 15:28:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:28:39 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-MuZAm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:28:39 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-kQVkYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:28:39 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-YgFGNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:28:39 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-90GcwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:28:39 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-1Gbdh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-AtCY2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-yzvFrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-5npPZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-d4ONFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-AqHsw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-i0MHcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-2Kzniw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-X6ZymL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-JgmctN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:40 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-neZJs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:28:41 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-0LaAuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:28:41 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Mt8xML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:28:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:28:41 INFO - Running setup.py install for manifestparser 15:28:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:28:41 INFO - Running setup.py install for mozcrash 15:28:41 INFO - Running setup.py install for mozdebug 15:28:41 INFO - Running setup.py install for mozdevice 15:28:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:28:41 INFO - Running setup.py install for mozfile 15:28:41 INFO - Running setup.py install for mozhttpd 15:28:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Running setup.py install for mozinfo 15:28:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Running setup.py install for mozInstall 15:28:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Running setup.py install for mozleak 15:28:42 INFO - Running setup.py install for mozlog 15:28:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Running setup.py install for moznetwork 15:28:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:28:42 INFO - Running setup.py install for mozprocess 15:28:43 INFO - Running setup.py install for mozprofile 15:28:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:28:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:28:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:28:43 INFO - Running setup.py install for mozrunner 15:28:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:28:43 INFO - Running setup.py install for mozscreenshot 15:28:43 INFO - Running setup.py install for moztest 15:28:43 INFO - Running setup.py install for mozversion 15:28:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:28:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:28:43 INFO - Cleaning up... 15:28:43 INFO - Return code: 0 15:28:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28:43 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')]} 15:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:43 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')]} 15:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:43 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:43 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 15:28:43 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 15:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:43 INFO - 'HOME': '/Users/cltbld', 15:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:43 INFO - 'LOGNAME': 'cltbld', 15:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:43 INFO - 'MOZ_NO_REMOTE': '1', 15:28:43 INFO - 'NO_EM_RESTART': '1', 15:28:43 INFO - 'PAGER': '/bin/cat', 15:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:43 INFO - 'PWD': '/builds/slave/test', 15:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:43 INFO - 'SHELL': '/bin/bash', 15:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:43 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:43 INFO - 'USER': 'cltbld', 15:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:43 INFO - 'XPC_FLAGS': '0x0', 15:28:43 INFO - 'XPC_SERVICE_NAME': '0', 15:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-CQY3PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-MGD0BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-3c_l2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Nh0Ysp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-3wu03p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-CIPFr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-JQYo00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-3GlRmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:28:44 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)) 15:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:28:44 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-xFMyLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-r940Ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-H76yoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-sOFIad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-4Ogj8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Bl7Yk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-BFP28T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-_dgkGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:28:45 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)) 15:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:28:45 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-yqSch_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:28:45 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)) 15:28:45 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)) 15:28:45 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)) 15:28:45 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)) 15:28:45 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)) 15:28:45 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)) 15:28:45 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)) 15:28:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:28:45 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 15:28:45 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 15:28:45 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 15:28:45 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 15:28:47 INFO - Downloading blessings-1.6.tar.gz 15:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:28:47 INFO - Installing collected packages: blessings 15:28:47 INFO - Running setup.py install for blessings 15:28:47 INFO - Successfully installed blessings 15:28:47 INFO - Cleaning up... 15:28:47 INFO - Return code: 0 15:28:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:28:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:28:47 INFO - Reading from file tmpfile_stdout 15:28:47 INFO - Current package versions: 15:28:47 INFO - blessings == 1.6 15:28:47 INFO - blobuploader == 1.2.4 15:28:47 INFO - docopt == 0.6.1 15:28:47 INFO - functools32 == 3.2.3-2 15:28:47 INFO - jsonschema == 2.5.1 15:28:47 INFO - manifestparser == 1.1 15:28:47 INFO - mozInstall == 1.13 15:28:47 INFO - mozcrash == 1.0 15:28:47 INFO - mozdebug == 0.1 15:28:47 INFO - mozdevice == 0.51 15:28:47 INFO - mozfile == 1.2 15:28:47 INFO - mozhttpd == 0.7 15:28:47 INFO - mozinfo == 0.10 15:28:47 INFO - mozleak == 0.1 15:28:47 INFO - mozlog == 3.4 15:28:47 INFO - moznetwork == 0.27 15:28:47 INFO - mozprocess == 0.25 15:28:47 INFO - mozprofile == 0.28 15:28:47 INFO - mozrunner == 6.13 15:28:47 INFO - mozscreenshot == 0.1 15:28:47 INFO - mozsystemmonitor == 0.3 15:28:47 INFO - moztest == 0.8 15:28:47 INFO - mozversion == 1.4 15:28:47 INFO - psutil == 3.1.1 15:28:47 INFO - requests == 1.2.3 15:28:47 INFO - wsgiref == 0.1.2 15:28:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28: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')]} 15:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28: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')]} 15:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:47 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:47 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 15:28:47 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 15:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:47 INFO - 'HOME': '/Users/cltbld', 15:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:47 INFO - 'LOGNAME': 'cltbld', 15:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:47 INFO - 'MOZ_NO_REMOTE': '1', 15:28:47 INFO - 'NO_EM_RESTART': '1', 15:28:47 INFO - 'PAGER': '/bin/cat', 15:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:47 INFO - 'PWD': '/builds/slave/test', 15:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:47 INFO - 'SHELL': '/bin/bash', 15:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:47 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:47 INFO - 'USER': 'cltbld', 15:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:47 INFO - 'XPC_FLAGS': '0x0', 15:28:47 INFO - 'XPC_SERVICE_NAME': '0', 15:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:48 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)) 15:28:48 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)) 15:28:48 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)) 15:28:48 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)) 15:28:48 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)) 15:28:48 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)) 15:28:48 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)) 15:28:48 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)) 15:28:48 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)) 15:28:48 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:28:48 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 15:28:48 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 15:28:48 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 15:28:48 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 15:28:49 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:28:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:28:49 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)) 15:28:49 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)) 15:28:49 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)) 15:28:49 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)) 15:28:49 INFO - Installing collected packages: marionette-driver 15:28:49 INFO - Running setup.py install for marionette-driver 15:28:49 INFO - Successfully installed marionette-driver 15:28:49 INFO - Cleaning up... 15:28:49 INFO - Return code: 0 15:28:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:28:49 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')]} 15:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:49 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')]} 15:28:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:49 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 0x10d2cfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0505b7630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, '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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 15:28:49 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 15:28:49 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 15:28:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:28:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:49 INFO - 'HOME': '/Users/cltbld', 15:28:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:49 INFO - 'LOGNAME': 'cltbld', 15:28:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:49 INFO - 'MOZ_NO_REMOTE': '1', 15:28:49 INFO - 'NO_EM_RESTART': '1', 15:28:49 INFO - 'PAGER': '/bin/cat', 15:28:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:49 INFO - 'PWD': '/builds/slave/test', 15:28:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:49 INFO - 'SHELL': '/bin/bash', 15:28:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:28:49 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:28:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:49 INFO - 'USER': 'cltbld', 15:28:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:49 INFO - 'XPC_FLAGS': '0x0', 15:28:49 INFO - 'XPC_SERVICE_NAME': '0', 15:28:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:28:50 INFO - Cleaning up... 15:28:50 INFO - Return code: 0 15:28:50 INFO - Running post-action listener: _resource_record_post_action 15:28:50 INFO - Running post-action listener: _start_resource_monitoring 15:28:50 INFO - Starting resource monitoring. 15:28:50 INFO - [mozharness: 2017-06-13 22:28:50.238355Z] Finished create-virtualenv step (success) 15:28:50 INFO - [mozharness: 2017-06-13 22:28:50.239281Z] Running install step. 15:28:50 INFO - Running pre-action listener: _resource_record_pre_action 15:28:50 INFO - Running main action method: install 15:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:28:50 INFO - Reading from file tmpfile_stdout 15:28:50 INFO - Detecting whether we're running mozinstall >=1.0... 15:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:28:50 INFO - Reading from file tmpfile_stdout 15:28:50 INFO - Output received: 15:28:50 INFO - Usage: mozinstall [options] installer 15:28:50 INFO - Options: 15:28:50 INFO - -h, --help show this help message and exit 15:28:50 INFO - -d DEST, --destination=DEST 15:28:50 INFO - Directory to install application into. [default: 15:28:50 INFO - "/builds/slave/test"] 15:28:50 INFO - --app=APP Application being installed. [default: firefox] 15:28:50 INFO - mkdir: /builds/slave/test/build/application 15:28:50 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'] 15:28:50 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 15:29:11 INFO - Reading from file tmpfile_stdout 15:29:11 INFO - Output received: 15:29:11 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:29:11 INFO - Running post-action listener: _resource_record_post_action 15:29:11 INFO - [mozharness: 2017-06-13 22:29:11.870631Z] Finished install step (success) 15:29:11 INFO - [mozharness: 2017-06-13 22:29:11.870785Z] Running setup-mitmproxy step. 15:29:11 INFO - Running pre-action listener: _resource_record_pre_action 15:29:11 INFO - Running main action method: setup_mitmproxy 15:29:11 INFO - Skipping: mitmproxy is not required 15:29:11 INFO - Running post-action listener: _resource_record_post_action 15:29:11 INFO - [mozharness: 2017-06-13 22:29:11.871318Z] Finished setup-mitmproxy step (success) 15:29:11 INFO - [mozharness: 2017-06-13 22:29:11.871425Z] Running run-tests step. 15:29:11 INFO - Running pre-action listener: _resource_record_pre_action 15:29:11 INFO - Running main action method: run_tests 15:29:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:29:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:29:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:29:11 INFO - 2017-06-13 15:29:11.886 screenresolution[1630:7342] starting screenresolution argv=screenresolution get 15:29:11 INFO - 2017-06-13 15:29:11.888 screenresolution[1630:7342] Display 0: 1600x1200x32@60 15:29:11 INFO - 2017-06-13 15:29:11.895 screenresolution[1631:7345] starting screenresolution argv=screenresolution list 15:29:11 INFO - Available Modes on Display 0 15:29:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:29:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:29:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:29:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:29:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:29:12 INFO - Intel Iris: 15:29:12 INFO - Chipset Model: Intel Iris 15:29:12 INFO - Type: GPU 15:29:12 INFO - Bus: Built-In 15:29:12 INFO - VRAM (Dynamic, Max): 1536 MB 15:29:12 INFO - Vendor: Intel (0x8086) 15:29:12 INFO - Device ID: 0x0a2e 15:29:12 INFO - Revision ID: 0x0009 15:29:12 INFO - Displays: 15:29:12 INFO - Display: 15:29:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:29:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:29:12 INFO - Display Serial Number: 1600x1200 60 15:29:12 INFO - Main Display: Yes 15:29:12 INFO - Mirror: Off 15:29:12 INFO - Online: Yes 15:29:12 INFO - Rotation: Supported 15:29:12 INFO - Return code: 0 15:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:29:12 INFO - Python 2.7.3 15:29:12 INFO - Return code: 0 15:29:12 INFO - grabbing minidump binary from tooltool 15:29:12 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')]} 15:29:12 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 0x10d025d30>, '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 0x10cfefcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d016be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:29:12 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 15:29:12 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 15:29:12 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 15:29:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:29:12 INFO - Return code: 0 15:29:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:29:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:29:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:29:12 INFO - ENV: RUST_BACKTRACE is now 1 15:29:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:29:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:29:12 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/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 15:29:12 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/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0015 --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 15:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRE4zpbwL5/Render', 15:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:12 INFO - 'HOME': '/Users/cltbld', 15:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:12 INFO - 'LOGNAME': 'cltbld', 15:29:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:29:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:12 INFO - 'MOZ_NO_REMOTE': '1', 15:29:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:29:12 INFO - 'NO_EM_RESTART': '1', 15:29:12 INFO - 'PAGER': '/bin/cat', 15:29:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:12 INFO - 'PWD': '/builds/slave/test', 15:29:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:12 INFO - 'RUST_BACKTRACE': '1', 15:29:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:29:12 INFO - 'SHELL': '/bin/bash', 15:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gcDF2MWpjt/Listeners', 15:29:12 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 15:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:12 INFO - 'USER': 'cltbld', 15:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:12 INFO - 'XPC_FLAGS': '0x0', 15:29:12 INFO - 'XPC_SERVICE_NAME': '0', 15:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:12 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/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 15:29:12 INFO - mozversion application_buildid: 20170613142334 15:29:12 INFO - mozversion application_changeset: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 15:29:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:29:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:29:12 INFO - mozversion application_name: Firefox 15:29:12 INFO - mozversion application_remotingname: firefox-dev 15:29:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:29:12 INFO - mozversion application_vendor: Mozilla 15:29:12 INFO - mozversion application_version: 55.0 15:29:12 INFO - mozversion platform_buildid: 20170613142334 15:29:12 INFO - mozversion platform_changeset: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 15:29:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:29:12 INFO - mozversion platform_version: 55.0 15:29:12 INFO - using testdate: 1497392952 15:29:12 INFO - actual date: 1497392952 15:29:12 INFO - starting webserver on 'localhost:49221' 15:29:12 INFO - SUITE-START | Running 2 tests 15:29:12 INFO - TEST-START | dromaeo_css 15:29:12 INFO - operating with platform_type : mac_ 15:29:12 INFO - Initialising browser for dromaeo_css test... 15:29:12 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpdxQCnf/profile 15:29:12 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 15:29:46 INFO - TEST-INFO | 1641: killed by SIGTERM 15:29:46 INFO - Browser initialized. 15:29:46 INFO - Running cycle 1/1 for dromaeo_css test... 15:29:46 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpdxQCnf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:29:47 INFO - PID 1652 | 2017-06-13 15:29:47.916 plugin-container[1653:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 15:29:47 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:47 INFO - PID 1652 | 2017-06-13 15:29:47.917 plugin-container[1653:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 15:29:47 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:47 INFO - PID 1652 | 2017-06-13 15:29:47.917 plugin-container[1653:7916] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:29:48 INFO - PID 1652 | 2017-06-13 15:29:48.640 plugin-container[1654:7963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 15:29:48 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:48 INFO - PID 1652 | 2017-06-13 15:29:48.640 plugin-container[1654:7963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x912f, name = 'com.apple.CFPasteboardClient' 15:29:48 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:48 INFO - PID 1652 | 2017-06-13 15:29:48.640 plugin-container[1654:7963] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:29:57 INFO - PID 1652 | RSS: Main: 214761472 15:29:57 INFO - PID 1652 | 15:33:01 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 15:33:03 INFO - PID 1652 | RSS: Main: 229228544 15:33:03 INFO - PID 1652 | 15:36:04 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 15:36:05 INFO - PID 1652 | RSS: Main: 239280128 15:36:05 INFO - PID 1652 | 15:39:07 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 15:39:07 INFO - PID 1652 | RSS: Main: 242094080 15:39:07 INFO - PID 1652 | 15:42:09 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 15:42:10 INFO - PID 1652 | RSS: Main: 254046208 15:42:10 INFO - PID 1652 | 15:45:12 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 15:45:13 INFO - PID 1652 | RSS: Main: 254525440 15:45:13 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 15:48:15 INFO - PID 1652 | RSS: Main: 252674048 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | __start_tp_report 15:48:15 INFO - PID 1652 | _x_x_mozilla_page_load 15:48:15 INFO - PID 1652 | _x_x_mozilla_page_load_details 15:48:15 INFO - PID 1652 | |i|pagename|runs| 15:48:15 INFO - PID 1652 | |0;dojo.html;3554.342446647369;3656.1042544576576;3665.211979424425;3668.1196512836927;3688.9855289795305;6248.468880145182;6252.84367890803;6271.874562508747;6298.181236439263;6333.873322533546;6430.485561155108;6431.485481161508;6434.871302573945;6443.548951573392;6452.0321951707265;5592.245046918675;5606.495415412612;5630.662160270376;5658;5953.58324917256;5626.127950167731;5685.488306052454;5772.451617096369;5785.334686511042;5789.7684092636455;4459.821607135711;4684.882877928062;5574.16387541872;5588.385277619459;5667.093265077609;5264.657797243167;5641.323041235078;5661.179129026268;5672.12082127271;5691.573132015074;4665.626749860003;5517.531009864167;5634.154876768476;5638.210650508932;5647.774089036434;6384.648844313561;6421;6433.581817181868;6458.38645328693;6474.546781725281;6732.528722989393;6734.090897728793;6807.2512023677355;6816;6979.511434199608;5799.246098007232;6962.060121883582;7011.913153461222;7038.260982596856;7083.397911177556;6319.652419116993;6342.143810585558;6441.227052753699;6554.246261679968;6566.3105373936005;7149.392301654418;7150.392216661642;7187.712491500281;7188.209296977328;7210.495265331481;5493.972530137324;6721.1598550181225;6749.8650026999185;6752.392284694303;6825.180978282638;6864.073350097673;6903.1371078615175;6909.654517274116;6924.93075069253;6997.265287144878;6955.6869940852785;6956.7217311307995;6966.825829354205;6994.545354551938;7000.579965202104;6962.199347075049;7011.5793052416;7018;7020.964895175492;7038.155421349368;5538.723063846792;5546.77812887488;5567.109262517978;5578.135389014709;5591.133374326986;4852.199387101089;4933.654644174873;4943.480934501826;4945.4807245238735;4972.154733695281;6254.74981000764;6299.433051025429;6335.081413195061;6389.424951754364;6396.840078998062;6886.449084073362;6939.861202775916;6982.33667801558;7077.681504332318;7088.787336379917;6472.870542589122;6499.707513161919;6500.252470965899;6507.089007538948;6533.954567269309;6105.908411373833;6155.322914479404;6196.504279657616;6226.159468471698;6226.25284965807;7092.4680648951535;7177.174624917992;7212.206657267697;7217.206107328189;7229.1325040993315;6839.418649414855;6861.519693621598;6956.165260168812;7035.788926332219;7081.964590177222;7122.821929451805;7188.209296977433;7194.568325900463;7237.131544214728;7261.745838895613;6954.547954382949;6992.930070699432;6992.965035174791;6999;7006.964965175345;7080.398166155935;7113.359797618239;7130.180029296697;7142.357187853118;7150.356467917913;6814.352636499525;6867.2102708189195;6880.208775990825;6894.586324820527;6915.619640919797;6606.570572912745;6675.365840245169;6718.328167183243;6791.422729068085;6880.827979300558;6684.331566843277;6689.632070236184;6736.157980252468;6737.3599508046655;6740.561863478858;6861.588304701734;6895.4138898194215;6902.482313826584;6907.378335949788;6909.861802764017;6461.515386346136;6695.330466953461;6707.061011458302;6730.293319201413;6736.427403670743 15:48:15 INFO - PID 1652 | |1;ext.html;646.3052218864718;772.5943879463281;806.2260230179028;861.1388611388611;1205.7046023724188;33617.82337618184;37415.81292093536;37428.812855935714;37496;37511.81244093783;31039.379212415737;37014;37112.25775484489;37209.62790372095;37233.06917327061;7757.495762775401;7888.290053895148;7894.289513943744;7902.407319451036;7908.52548847071;7697.153313135551;7789.922100779005;7807.453478256524;7983.12185659577;8139.064007639108;8237.258646721793;8244.670213191495;8288.58557072147;8292.792680183013;8302.958485207595;8153.388495862804;8204.220599043081;8271.793205169859;8274.2139496748;8277;8127.187281271885;8155.796105097361;8169.468984515987;8198.057223419324;8209.014918209794;35241.64758352409;35323;35428.822855885555;35527.75652852138;35584;3667.468217108504;3696.77819330841;3705.2589482103685;3712.2761061593;3730.664240218366;29059.854700726362;29142.41715165706;29150.85424572885;29160.270993225127;29198.854005729834;3141.5915930929064;3163.31988622445;3186.7291280240975;3188.4260833050275;3208.7593430492807;249301;250359;323828.7617123822;326188;326859;38797.22405551873;38991.610083899366;46805;47345.526544734115;47483.76258118687;49345.753271233414;49481.752591237535;49557.25664115041;49592.504074958786;49644;15211.543653690407;15306.770398444032;15416.845831541765;15427.84572154264;15439.536813895602;12380.876191238152;12553.372331383576;12614.747705045847;12616.24302541832;12646.683832904251;13147.34263286851;13382.397792099378;13408.396622152026;13415.731685366238;13469.326533673277;5709.65742055478;5782.017057100303;5804.071348584291;5804.883902322014;5820.476157145878;923.4828496042238;966.1884017425256;986.8322385194534;1007.4610083605236;1047.512906498482;22189.11243550272;22770.975306111264;23241.535169296858;23319.692369371907;24004.79976001228;974.7075877236942;1094;1107.4739498738195;1112.410422476089;1171.6133675887106;10603.257771956043;12912.612621621367;15264.46574369914;15769.29038193284;16354.264058117413;321697;326147;326271;326735;326751;55262.72368638131;55561;55800.72099639557;55888.88222235532;55891.720541397844;1333.6665833541615;1496.4388354367113;1505.510709019573;1511.4860947277978;1521.8554237347655;27567;29090.418191635625;29492.115236542937;29605;29613;36804.63195368119;36845.81577092098;37053.81473092617;37056.25887482181;37069.25861482809;297490;297547.5122624373;297767.5111624428;298227;298966;27784.444311114068;28398.43203135967;28411.573826392207;33180;33253.50119748157;35045;35176;39112.022199445244;39495.407568885916;39658.603413966644;23453.413664657837;26976.460470790866;27689;29574.704252957195;30037.5494367589;28856.855715721285;28924.566131508465;28953.565696514146;29644.962426314974;29797.553036704034;33351.83324083364;33496.83251583727;33591.16022099369;34336.656633434344;35262;28415.28961775972;28499.857500712365;29161.833526659546;29656.703432965394;29807;16962.74555881638;17904.55238619045;18254.45236642903;18260;18269.634607307515 15:48:15 INFO - PID 1652 | |2;jquery.html;464.3104989091199;525.1780962793229;525.4062908912928;526.4735264735264;548.0600769679997;20536.97315134245;22591.322260332196;22815.885920570374;23112.07551697934;23293.76706232937;23588.764112358873;23722.406939826513;24190.395240119007;24228.75771242287;24297.878510607465;6162.907556386658;6370.745170193187;6374.776882809103;6376.234851817789;6379.87240255197;6053.182820319266;6250.624962502242;6326.114343991845;6350.333215012426;6369.394907483782;6325.304216536178;6333.841653958665;6343.048542718583;6350.26971898233;6354.904676429857;6305.054241863734;6341.302456729756;6345.460635845959;6355.364463553654;6358.459530939876;5805.854853628651;5811.244538210006;5817.243758311392;5819.67991760453;5821.7380217890295;27286.181414557595;29019;29266.414671706447;29834.80660773588;30063.098107056823;2241.3276017194776;2287.0737351372723;2292.839501234914;2301.976980230193;2302.9424264393356;18589.163487643087;19022.80977190224;19029.333973310866;19085.0457477127;19148.94680792556;2161.200355868323;2180.062573093569;2219.578280126787;2258.6499092640665;2262.7171603549555;140900;170917;183904.08047959674;186926;220890;14297.213653248964;14581;14915.925420372829;15186.316615752097;15237.695246095016;20263.696044559343;20354;20375.08312125958;20395.4901127473;20639.277625283044;10225.386476811267;10411.427371494488;10422.374657520422;10434.269601127848;10440.843387349196;8857.778555536162;9328;9852.704418867446;9889.45607991553;9934.205263578897;8792.692255771017;8936.553172341357;8982.775430614156;8992.8651070234;9003.954980225057;2242.6299660555824;2647.2984659065364;2648.8808003639883;2665.3469899874267;2758.6413766210667;705.2594775485719;932.7901222225081;934.7429456899271;951.3578334624101;991.8214721350226;20592.27927022547;21168;21320.36038918835;21507.89246053791;21631.783682162975;902.7291812456236;958.4281777884275;972.2756546372998;995.5719040812376;1025.6102680981328;12621.558245461549;12859.742805143846;14219.217943013233;14483.275836208148;14561.490347837775;580209;599891;600181;606244;617365;164978.1751091237;166646;166848;167305;169967;1022.9693109206736;1089.9073578745895;1098.4123493930708;1100.339796122336;1179.2747460312019;19783.109760060543;21430.78569214288;23031.88484057569;24100.87949560241;24463.899124539796;71710.92433613604;73563.89654155084;74606.8808967855;76036;78157.21842781726;94843.52578237065;98423.52364714391;98824;99965.00069998705;100459.49310760491;11550.075993920635;11880.34658093813;12001.279923204636;12034.879651203375;12090.335031573348;18403.631927361646;18487.075646217905;18656;18674.533136671147;18784.53038674044;12068.577599783965;14675.48635797742;15301.923490382922;15339.38642454334;15527.068375897483;13739.175649461064;13876.306184690524;14055.437782488585;14149;14220.786688199893;13907.930460347634;15676.372945082068;15812.209389530248;15954.122523261332;15973.68052638964;11653.30080195191;11751.882481175078;11824.940875295568;11858.11064170208;11919.880801191877;14746.926265368604;14865.256737162883;14941;14955.551333459578;15054.397824086915 15:48:15 INFO - PID 1652 | |3;mootools.html;1091.4433638844187;1294.2622705058118;1451.3468938977462;1452.949146779863;1476.778483227516;13578.660533486667;14175.291235438213;14187.077839940397;14208.076475029116;14209.360578773954;13648.727025459484;14091.718165636681;14106;14161.07952983058;14199.574012779607;2828.830270183785;2841.3891013432108;2857.3285277959662;2877.6115224444643;2878.7840911931585;2399.9760002400017;2817.7464028237455;2856.9285767855868;2870.2967772895568;2870.5550639650887;2805.116388337677;2842.403095349979;2847.857607119646;2850.643669541307;2851.5580085086845;2788.5538313869783;2802.6776920654183;2807.887684492618;2811.170704642125;2813.563897595876;2726.2366537369567;2760.3789147441867;2770.1551026936777;2770.501309764242;2772.029789573633;13613.387397567034;13697.863021369758;13932.024758266925;13957.162570245717;13963.720725585432;6696.8615335392615;6744.494162937799;7710.57591832449;7940.920590794076;8020.598970051533;4161.25097482453;4687.88280292992;4880.389951256093;5011.398632164127;5012.548870601663;1206.6500714792699;1410.9294535273298;1420.5596265157835;1428.19307091494;1435.519101101122;16213.2704028319;19118.139683714126;20842.895785520974;21000.05499752515;21033;6165.1985241919165;6844.931550684529;7081.787546373617;7131.322524360177;7161.176464706521;12291.385430728427;14716.632084197767;14740.11559306445;14747.262636868114;14770.039947403384;7585.924140758632;7734.922650773533;7759.495632783851;7761.495502792301;7801.921980780233;6984.406325462291;7159.821004474929;7233.1681856587165;7239.601821899741;7261.636918154177;4382.561743825592;5358.705271210043;5372.489613486712;5437.728113594305;5439.891202175934;1573.417835400909;1575.5903465099157;1587.103286643043;1598.3606557376909;1601.1673929556566;811.3874025111004;836.9037560680478;846.7036537211926;848.3510114762212;869.8956125265008;7178.353948144692;7216.097987751531;7499.887501687479;7560.659770310349;7575.24247575238;703.8662654095706;707.2785758526275;720.6901032556051;721.487743701967;724.655788500458;4206.915861682749;4210.326347784401;4394.406755088086;4795.064962332312;5157.406898206679;18366.449006529827;18929.905350473437;18976.52558686044;18978.52553686169;18982;17824.732629010574;18825.623487530174;18887.150078246512;18919.52701182454;18922.432327029936;9291.67479138227;9638.614455421844;9754;9803.313768036169;9829.459379734184;7507.942534018006;7752.573608451364;7855.567943763148;7880.645370958206;8184.386171037077;16961.66076678484;17419.39032133869;17447.912760436626;17458.563535911704;17741.645167096845;16481.83518164851;16930;18085.728714069035;18161.636767264317;18186.545336366697;10908.781824363627;11016.944915275373;11225.550977960791;11333.773324533298;11480;13142.868571314164;13221.074524783173;13338.866611333762;13346.666333341744;13365.799513007507;10168.389896606228;10266.281360304707;10403.791924161626;10530.05229529346;10703.304285221435;8488.193621605938;9095.681651142178;10004.649837255662;10064.144547713233;10333.225008124271;9353.625854965725;9424.481653509123;9512.571934262836;9517.000714924834;9520.809583808423;6162.198914141133;6576.506761992774;6929.203141638574;7012.088428504466;7178.641067946686;5609.915851262316;7039.788806335818;7227.096612923385;7403.51877127985;7435.182129965591 15:48:15 INFO - PID 1652 | |4;prototype.html;436.74886940009503;450.2570758248889;465.8113464047061;477.3817905811972;480.66593717366425;5704.001799685049;6193.442590166884;6389.78594067127;6642.634655093968;6644.807213619506;4978.5270399312;5870.647761134335;6367.586106903048;6512.902306465407;6522.608643481394;4852.514748525137;4858.805647774085;4860.513948605147;4872.926906096412;4895.9510404895855;4013.2776100301935;4126.814293356806;4810.350602668647;4822.541858523435;4856.684315519497;4642.257238841786;4786.353842231307;4819.650343011608;4843.418789745236;4860.878478038042;3737.8691745788765;4087.0395457067566;4349.391085248068;4494.662900282476;4840.104580652555;3555.839987200582;4246.278132717445;4255.446791917031;4258.48898132223;4259.744415335059;5296.708681022542;5743.5118014968775;6211.937880621226;6294.213223347082;6325.778597749057;4895.926561101586;5737.540996720294;5746.511546518551;5756.769729210827;5760.222369980041;5929.73316200772;6284.874302513924;6908.171019477644;6993.2657071007325;7027.262137475543;4847.248676455155;4858.708477491326;4875.780589873464;4879.731614761186;4886.975565122188;7359.153697324823;7373.88939165913;7400.555966642019;7402.33378995893;7429;4242.918055895727;4936.876578085505;4954.455009948902;4968.90062198754;4990.6257030722845;4643.674942754043;4978.5270399312;5042.873928151826;5044.848654540369;5049.091163590589;5734.397888221677;5751.683657398883;5756.913646295309;5770.596058275964;5773.546242846774;7002.719891204397;7009.12385951756;7056.823579410556;7059.858802823915;7095.467839912028;1591.3873158833933;1715.7340612205128;1717.4761697682327;1721.2254485481583;1722.7071397862394;1055.8680164979378;1095.9040959040958;1143.7883991461606;1154.7459558897028;1161.5702190189684;6254.312025677172;6470.38531339522;6609.926384231259;6633.834154146185;6642.634655094016;6035.78874739382;7211.711531538785;7249.420046396275;7255;7281.927180728231;6518.706658200393;6537.771178008747;6540.247871494744;6555;6601.636909969901;3151.700588444094;3378.7803792753393;3408.9659103409144;3725.4598083278315;3790.6209379062425;7298.8175295616975;7385.4460915431555;7387.8153046174275;7395.334419902235;7407.703691852265;6980.917957716562;7111.90198891318;7352.338289553966;7377.483576149618;7378.483506154625;6212.875742485216;6818.772620928082;6914.30599503125;7163.928360716431;7472.85054298911;6678.332166783282;7250.456215783943;7258.600776957318;7259.382952449101;7269.45479089081;5909.724755968897;6705.765298214539;7022.613756243456;7079.964600176966;7108;5794.681292528951;6503.317151699192;7311.707531698672;7401.33387995083;7484.43866709988;4837.612990960785;4875.536824001714;4877.5366340197625;4917.557419832089;4928.605711542997;6578.8355291118105;7136;7153.2131465537705;7164.9641751793;7238.674259658434;6706.893362595117;6866.931330686829;6910.4391101872525;6935.7225710971;6963.338482844122;6056.84857878539;6092.45167934888;6640.900386494303;6652.501062420434;6913.75801846933;6770.93229067703;6802.3537763912345;6825.829354265985;6837.658117094026;6849.520533562602;6369.522285828489;6373.362663733589;6967.1291088613925;6980.11392025574;7001.8949715755325;6217.96891015542;6772.288909664414;6781.321867813179;6804.829879253059;6858.348456896587 15:48:15 INFO - PID 1652 | |5;yui.html;736.795339619722;775.3796962430056;812.731798506493;822.5558198572771;858.9441686290389;16544.66910661786;16963.98216107033;17104.401345952894;17269.82730172698;17289.221985010674;17518.474445766617;18387.72418413725;18597.814021859813;18676.159572819222;18746.718799218022;995.7809281957958;1004.5027711282925;1016.6086056868122;1019.1642852860657;1019.8419245017034;1012.185190921308;1013.002192840052;1013.1843865688118;1015.6597539824131;1016.6289304403876;1009.5457044330044;1052.41590917041;1057.746140926176;1060.1624716473975;1064.435849000031;1028.198005555664;1039.2880876599554;1040.6149724601871;1043.2332235806714;1045.8744950605899;758.3137260778949;760.68811787167;760.6995236881401;762.6339357108619;764.2319468933686;17453.12734363277;17582;17614.823851761575;17775.377861774778;17791.46625601234;14872;17606.471805845846;17790.46628601144;18079.27682892696;18164.545886352815;14771.630709232248;14778.26108694561;14855.92572037133;14940.477083302141;14969.775453368207;3560.359135355635;3585.4980302757635;3591.676749092568;3600.027992442052;3852.595477474853;146093.26953365165;150982;152460;153351;158611.2069439657;13523.918086553052;13833.308334583231;13876.375563099684;14113;14177.14937103777;27411.451770964468;27417.588736168975;27580.586291205647;27620.447591048065;27649;8059.073206581201;8135.511869287861;8163.673453061811;8194.385421093442;8223.917760822435;4126.504819421689;4129.855455059059;4148.315527937856;4197.223513649985;4215.641670458045;4300.290452075373;4488.685791994594;4494.640428765691;4497;4516.638668906479;768.8270139218743;905.7509184974185;908.6592527802075;984.2863923655293;993.0764389118045;559.9076152434884;593.4599514441837;597.9774585458914;598.853280946171;622.8972219583718;9744.658936937172;12247.142700011093;12265.2640841548;12539.561115360919;12947.546835860889;591.6982339007051;591.9852003699942;593.6794131169206;607.9939200607937;621.4034526854181;5132.8460146195675;5545.77816887328;5692.576855786054;5740.626859254218;6023.367546407651;142942.8558571622;143448;147120;151347;153340.23329883278;113109.4344528272;120517;122101;125613.60352387623;127493.72506274818;107568.46215769027;108046.45976770065;109107;109979.45010274898;111808.4409577947;10729.195310351599;10890.128789696646;11774.94112529432;11821.290722556674;11997.700057498632;41248.79375603103;42333;42795.358069629605;45189.870253243935;48603.756981214865;74831;77957;78305.82541261772;80524;85155;17346.73979890328;17456.30174792994;17615.295388184328;17649.470515884543;17976.2809487619;18128.546786330448;18195.636087278446;19332.90333548323;19831.60336793285;19885.403437896886;17756.822431775516;17781.666375022185;18013.64897632709;18063.819361806738;18156.636867262845;14310.71378572402;17043.940116149144;18014.2794288227;18084.72872906934;18248.908755456137;16437.67124657524;17030.57423564421;17613.03128327903;17840.3755868544;17992;11992.160548761503;12005.39973001364;12006.159568830099;12228.816567751668;12906.41921113571;13621.659458513617;14147.504837330644;14251.429942802586;14260.643483912985;14262.286885655883 15:48:15 INFO - PID 1652 | __end_tp_report 15:48:15 INFO - PID 1652 | __start_cc_report 15:48:15 INFO - PID 1652 | _x_x_mozilla_cycle_collect,409 15:48:15 INFO - PID 1652 | __end_cc_report 15:48:15 INFO - PID 1652 | __startTimestamp1497394095644__endTimestamp 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | ------- Summary: start ------- 15:48:15 INFO - PID 1652 | Number of tests: 6 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6390.95 Median:6695.33 stddev:800.74 (12.0%) stddev-sans-first:771.65 15:48:15 INFO - PID 1652 | Values: 3554.3 3656.1 3665.2 3668.1 3689.0 6248.5 6252.8 6271.9 6298.2 6333.9 6430.5 6431.5 6434.9 6443.5 6452.0 5592.2 5606.5 5630.7 5658.0 5953.6 5626.1 5685.5 5772.5 5785.3 5789.8 4459.8 4684.9 5574.2 5588.4 5667.1 5264.7 5641.3 5661.2 5672.1 5691.6 4665.6 5517.5 5634.2 5638.2 5647.8 6384.6 6421.0 6433.6 6458.4 6474.5 6732.5 6734.1 6807.3 6816.0 6979.5 5799.2 6962.1 7011.9 7038.3 7083.4 6319.7 6342.1 6441.2 6554.2 6566.3 7149.4 7150.4 7187.7 7188.2 7210.5 5494.0 6721.2 6749.9 6752.4 6825.2 6864.1 6903.1 6909.7 6924.9 6997.3 6955.7 6956.7 6966.8 6994.5 7000.6 6962.2 7011.6 7018.0 7021.0 7038.2 5538.7 5546.8 5567.1 5578.1 5591.1 4852.2 4933.7 4943.5 4945.5 4972.2 6254.7 6299.4 6335.1 6389.4 6396.8 6886.4 6939.9 6982.3 7077.7 7088.8 6472.9 6499.7 6500.3 6507.1 6534.0 6105.9 6155.3 6196.5 6226.2 6226.3 7092.5 7177.2 7212.2 7217.2 7229.1 6839.4 6861.5 6956.2 7035.8 7082.0 7122.8 7188.2 7194.6 7237.1 7261.7 6954.5 6992.9 6993.0 6999.0 7007.0 7080.4 7113.4 7130.2 7142.4 7150.4 6814.4 6867.2 6880.2 6894.6 6915.6 6606.6 6675.4 6718.3 6791.4 6880.8 6684.3 6689.6 6736.2 6737.4 6740.6 6861.6 6895.4 6902.5 6907.4 6909.9 6461.5 6695.3 6707.1 6730.3 6736.4 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:44804.16 Median:180019.50 stddev:80767.80 (44.9%) stddev-sans-first:80926.33 15:48:15 INFO - PID 1652 | Values: 646.3 772.6 806.2 861.1 1205.7 33617.8 37415.8 37428.8 37496.0 37511.8 31039.4 37014.0 37112.3 37209.6 37233.1 7757.5 7888.3 7894.3 7902.4 7908.5 7697.2 7789.9 7807.5 7983.1 8139.1 8237.3 8244.7 8288.6 8292.8 8303.0 8153.4 8204.2 8271.8 8274.2 8277.0 8127.2 8155.8 8169.5 8198.1 8209.0 35241.6 35323.0 35428.8 35527.8 35584.0 3667.5 3696.8 3705.3 3712.3 3730.7 29059.9 29142.4 29150.9 29160.3 29198.9 3141.6 3163.3 3186.7 3188.4 3208.8 249301.0 250359.0 323828.8 326188.0 326859.0 38797.2 38991.6 46805.0 47345.5 47483.8 49345.8 49481.8 49557.3 49592.5 49644.0 15211.5 15306.8 15416.8 15427.8 15439.5 12380.9 12553.4 12614.7 12616.2 12646.7 13147.3 13382.4 13408.4 13415.7 13469.3 5709.7 5782.0 5804.1 5804.9 5820.5 923.5 966.2 986.8 1007.5 1047.5 22189.1 22771.0 23241.5 23319.7 24004.8 974.7 1094.0 1107.5 1112.4 1171.6 10603.3 12912.6 15264.5 15769.3 16354.3 321697.0 326147.0 326271.0 326735.0 326751.0 55262.7 55561.0 55800.7 55888.9 55891.7 1333.7 1496.4 1505.5 1511.5 1521.9 27567.0 29090.4 29492.1 29605.0 29613.0 36804.6 36845.8 37053.8 37056.3 37069.3 297490.0 297547.5 297767.5 298227.0 298966.0 27784.4 28398.4 28411.6 33180.0 33253.5 35045.0 35176.0 39112.0 39495.4 39658.6 23453.4 26976.5 27689.0 29574.7 30037.5 28856.9 28924.6 28953.6 29645.0 29797.6 33351.8 33496.8 33591.2 34336.7 35262.0 28415.3 28499.9 29161.8 29656.7 29807.0 16962.7 17904.6 18254.5 18260.0 18269.6 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:41143.60 Median:12424.99 stddev:103403.92 (832.2%) stddev-sans-first:103648.88 15:48:15 INFO - PID 1652 | Values: 464.3 525.2 525.4 526.5 548.1 20537.0 22591.3 22815.9 23112.1 23293.8 23588.8 23722.4 24190.4 24228.8 24297.9 6162.9 6370.7 6374.8 6376.2 6379.9 6053.2 6250.6 6326.1 6350.3 6369.4 6325.3 6333.8 6343.0 6350.3 6354.9 6305.1 6341.3 6345.5 6355.4 6358.5 5805.9 5811.2 5817.2 5819.7 5821.7 27286.2 29019.0 29266.4 29834.8 30063.1 2241.3 2287.1 2292.8 2302.0 2302.9 18589.2 19022.8 19029.3 19085.0 19148.9 2161.2 2180.1 2219.6 2258.6 2262.7 140900.0 170917.0 183904.1 186926.0 220890.0 14297.2 14581.0 14915.9 15186.3 15237.7 20263.7 20354.0 20375.1 20395.5 20639.3 10225.4 10411.4 10422.4 10434.3 10440.8 8857.8 9328.0 9852.7 9889.5 9934.2 8792.7 8936.6 8982.8 8992.9 9004.0 2242.6 2647.3 2648.9 2665.3 2758.6 705.3 932.8 934.7 951.4 991.8 20592.3 21168.0 21320.4 21507.9 21631.8 902.7 958.4 972.3 995.6 1025.6 12621.6 12859.7 14219.2 14483.3 14561.5 580209.0 599891.0 600181.0 606244.0 617365.0 164978.2 166646.0 166848.0 167305.0 169967.0 1023.0 1089.9 1098.4 1100.3 1179.3 19783.1 21430.8 23031.9 24100.9 24463.9 71710.9 73563.9 74606.9 76036.0 78157.2 94843.5 98423.5 98824.0 99965.0 100459.5 11550.1 11880.3 12001.3 12034.9 12090.3 18403.6 18487.1 18656.0 18674.5 18784.5 12068.6 14675.5 15301.9 15339.4 15527.1 13739.2 13876.3 14055.4 14149.0 14220.8 13907.9 15676.4 15812.2 15954.1 15973.7 11653.3 11751.9 11824.9 11858.1 11919.9 14746.9 14865.3 14941.0 14955.6 15054.4 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:8458.91 Median:2823.29 stddev:5665.52 (200.7%) stddev-sans-first:5654.36 15:48:15 INFO - PID 1652 | Values: 1091.4 1294.3 1451.3 1452.9 1476.8 13578.7 14175.3 14187.1 14208.1 14209.4 13648.7 14091.7 14106.0 14161.1 14199.6 2828.8 2841.4 2857.3 2877.6 2878.8 2400.0 2817.7 2856.9 2870.3 2870.6 2805.1 2842.4 2847.9 2850.6 2851.6 2788.6 2802.7 2807.9 2811.2 2813.6 2726.2 2760.4 2770.2 2770.5 2772.0 13613.4 13697.9 13932.0 13957.2 13963.7 6696.9 6744.5 7710.6 7940.9 8020.6 4161.3 4687.9 4880.4 5011.4 5012.5 1206.7 1410.9 1420.6 1428.2 1435.5 16213.3 19118.1 20842.9 21000.1 21033.0 6165.2 6844.9 7081.8 7131.3 7161.2 12291.4 14716.6 14740.1 14747.3 14770.0 7585.9 7734.9 7759.5 7761.5 7801.9 6984.4 7159.8 7233.2 7239.6 7261.6 4382.6 5358.7 5372.5 5437.7 5439.9 1573.4 1575.6 1587.1 1598.4 1601.2 811.4 836.9 846.7 848.4 869.9 7178.4 7216.1 7499.9 7560.7 7575.2 703.9 707.3 720.7 721.5 724.7 4206.9 4210.3 4394.4 4795.1 5157.4 18366.4 18929.9 18976.5 18978.5 18982.0 17824.7 18825.6 18887.2 18919.5 18922.4 9291.7 9638.6 9754.0 9803.3 9829.5 7507.9 7752.6 7855.6 7880.6 8184.4 16961.7 17419.4 17447.9 17458.6 17741.6 16481.8 16930.0 18085.7 18161.6 18186.5 10908.8 11016.9 11225.6 11333.8 11480.0 13142.9 13221.1 13338.9 13346.7 13365.8 10168.4 10266.3 10403.8 10530.1 10703.3 8488.2 9095.7 10004.6 10064.1 10333.2 9353.6 9424.5 9512.6 9517.0 9520.8 6162.2 6576.5 6929.2 7012.1 7178.6 5609.9 7039.8 7227.1 7403.5 7435.2 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5630.71 Median:6269.59 stddev:1751.37 (27.9%) stddev-sans-first:1712.35 15:48:15 INFO - PID 1652 | Values: 436.7 450.3 465.8 477.4 480.7 5704.0 6193.4 6389.8 6642.6 6644.8 4978.5 5870.6 6367.6 6512.9 6522.6 4852.5 4858.8 4860.5 4872.9 4896.0 4013.3 4126.8 4810.4 4822.5 4856.7 4642.3 4786.4 4819.7 4843.4 4860.9 3737.9 4087.0 4349.4 4494.7 4840.1 3555.8 4246.3 4255.4 4258.5 4259.7 5296.7 5743.5 6211.9 6294.2 6325.8 4895.9 5737.5 5746.5 5756.8 5760.2 5929.7 6284.9 6908.2 6993.3 7027.3 4847.2 4858.7 4875.8 4879.7 4887.0 7359.2 7373.9 7400.6 7402.3 7429.0 4242.9 4936.9 4954.5 4968.9 4990.6 4643.7 4978.5 5042.9 5044.8 5049.1 5734.4 5751.7 5756.9 5770.6 5773.5 7002.7 7009.1 7056.8 7059.9 7095.5 1591.4 1715.7 1717.5 1721.2 1722.7 1055.9 1095.9 1143.8 1154.7 1161.6 6254.3 6470.4 6609.9 6633.8 6642.6 6035.8 7211.7 7249.4 7255.0 7281.9 6518.7 6537.8 6540.2 6555.0 6601.6 3151.7 3378.8 3409.0 3725.5 3790.6 7298.8 7385.4 7387.8 7395.3 7407.7 6980.9 7111.9 7352.3 7377.5 7378.5 6212.9 6818.8 6914.3 7163.9 7472.9 6678.3 7250.5 7258.6 7259.4 7269.5 5909.7 6705.8 7022.6 7080.0 7108.0 5794.7 6503.3 7311.7 7401.3 7484.4 4837.6 4875.5 4877.5 4917.6 4928.6 6578.8 7136.0 7153.2 7165.0 7238.7 6706.9 6866.9 6910.4 6935.7 6963.3 6056.8 6092.5 6640.9 6652.5 6913.8 6770.9 6802.4 6825.8 6837.7 6849.5 6369.5 6373.4 6967.1 6980.1 7001.9 6218.0 6772.3 6781.3 6804.8 6858.3 15:48:15 INFO - PID 1652 | 15:48:15 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:26493.98 Median:17786.07 stddev:40802.33 (229.4%) stddev-sans-first:40870.96 15:48:15 INFO - PID 1652 | Values: 736.8 775.4 812.7 822.6 858.9 16544.7 16964.0 17104.4 17269.8 17289.2 17518.5 18387.7 18597.8 18676.2 18746.7 995.8 1004.5 1016.6 1019.2 1019.8 1012.2 1013.0 1013.2 1015.7 1016.6 1009.5 1052.4 1057.7 1060.2 1064.4 1028.2 1039.3 1040.6 1043.2 1045.9 758.3 760.7 760.7 762.6 764.2 17453.1 17582.0 17614.8 17775.4 17791.5 14872.0 17606.5 17790.5 18079.3 18164.5 14771.6 14778.3 14855.9 14940.5 14969.8 3560.4 3585.5 3591.7 3600.0 3852.6 146093.3 150982.0 152460.0 153351.0 158611.2 13523.9 13833.3 13876.4 14113.0 14177.1 27411.5 27417.6 27580.6 27620.4 27649.0 8059.1 8135.5 8163.7 8194.4 8223.9 4126.5 4129.9 4148.3 4197.2 4215.6 4300.3 4488.7 4494.6 4497.0 4516.6 768.8 905.8 908.7 984.3 993.1 559.9 593.5 598.0 598.9 622.9 9744.7 12247.1 12265.3 12539.6 12947.5 591.7 592.0 593.7 608.0 621.4 5132.8 5545.8 5692.6 5740.6 6023.4 142942.9 143448.0 147120.0 151347.0 153340.2 113109.4 120517.0 122101.0 125613.6 127493.7 107568.5 108046.5 109107.0 109979.5 111808.4 10729.2 10890.1 11774.9 11821.3 11997.7 41248.8 42333.0 42795.4 45189.9 48603.8 74831.0 77957.0 78305.8 80524.0 85155.0 17346.7 17456.3 17615.3 17649.5 17976.3 18128.5 18195.6 19332.9 19831.6 19885.4 17756.8 17781.7 18013.6 18063.8 18156.6 14310.7 17043.9 18014.3 18084.7 18248.9 16437.7 17030.6 17613.0 17840.4 17992.0 11992.2 12005.4 12006.2 12228.8 12906.4 13621.7 14147.5 14251.4 14260.6 14262.3 15:48:15 INFO - PID 1652 | -------- Summary: end -------- 15:48:15 INFO - PID 1652 | 15:48:16 INFO - TEST-INFO | 1652: exit 0 15:48:16 INFO - TEST-OK | dromaeo_css | took 1143702ms 15:48:16 INFO - TEST-START | kraken 15:48:16 INFO - operating with platform_type : mac_ 15:48:16 INFO - Initialising browser for kraken test... 15:48:16 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpAc5U4q/profile 15:48:16 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 15:48:49 INFO - TEST-INFO | 1663: killed by SIGTERM 15:48:49 INFO - Browser initialized. 15:48:49 INFO - Running cycle 1/1 for kraken test... 15:48:49 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpAc5U4q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:51 INFO - PID 1667 | 2017-06-13 15:48:51.536 plugin-container[1668:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 15:48:51 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:51 INFO - PID 1667 | 2017-06-13 15:48:51.537 plugin-container[1668:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 15:48:51 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:51 INFO - PID 1667 | 2017-06-13 15:48:51.537 plugin-container[1668:10530] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:48:52 INFO - PID 1667 | 2017-06-13 15:48:52.306 plugin-container[1669:10573] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 15:48:52 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:52 INFO - PID 1667 | 2017-06-13 15:48:52.306 plugin-container[1669:10573] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 15:48:52 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:52 INFO - PID 1667 | 2017-06-13 15:48:52.306 plugin-container[1669:10573] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:49:01 INFO - PID 1667 | RSS: Main: 214831104 15:49:01 INFO - PID 1667 | 15:49:04 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 15:49:05 INFO - PID 1667 | RSS: Main: 228360192 15:49:05 INFO - PID 1667 | 15:49:07 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 15:49:08 INFO - PID 1667 | RSS: Main: 240205824 15:49:08 INFO - PID 1667 | 15:49:11 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 15:49:12 INFO - PID 1667 | RSS: Main: 242286592 15:49:12 INFO - PID 1667 | 15:49:14 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 15:49:15 INFO - PID 1667 | RSS: Main: 245764096 15:49:15 INFO - PID 1667 | 15:49:17 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:17 INFO - PID 1667 | RSS: Main: 246743040 15:49:17 INFO - PID 1667 | 15:49:20 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:21 INFO - PID 1667 | RSS: Main: 251469824 15:49:21 INFO - PID 1667 | 15:49:24 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:24 INFO - PID 1667 | RSS: Main: 251887616 15:49:24 INFO - PID 1667 | 15:49:27 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 15:49:28 INFO - PID 1667 | RSS: Main: 251928576 15:49:28 INFO - PID 1667 | 15:49:29 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:49:30 INFO - PID 1667 | RSS: Main: 251416576 15:49:30 INFO - PID 1667 | 15:49:32 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:49:32 INFO - PID 1667 | RSS: Main: 251281408 15:49:32 INFO - PID 1667 | 15:49:34 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:49:35 INFO - PID 1667 | RSS: Main: 251154432 15:49:35 INFO - PID 1667 | 15:49:38 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:49:39 INFO - PID 1667 | RSS: Main: 251424768 15:49:39 INFO - PID 1667 | 15:49:41 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:49:42 INFO - PID 1667 | RSS: Main: 251424768 15:49:42 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 15:49:44 INFO - PID 1667 | RSS: Main: 253046784 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | __start_tp_report 15:49:44 INFO - PID 1667 | _x_x_mozilla_page_load 15:49:44 INFO - PID 1667 | _x_x_mozilla_page_load_details 15:49:44 INFO - PID 1667 | |i|pagename|runs| 15:49:44 INFO - PID 1667 | |0;ai-astar;103;126;152;159;115;114;115;101;112;116 15:49:44 INFO - PID 1667 | |1;audio-beat-detection;142;119;186;169;110;129;111;146;140;109 15:49:44 INFO - PID 1667 | |2;audio-dft;268;280;195;278;281;194;196;199;200;193 15:49:44 INFO - PID 1667 | |3;audio-fft;68;77;80;145;62;57;59;62;135;82 15:49:44 INFO - PID 1667 | |4;audio-oscillator;69;71;108;68;69;72;109;69;70;117 15:49:44 INFO - PID 1667 | |5;imaging-gaussian-blur;93;94;94;114;93;93;92;92;111;90 15:49:44 INFO - PID 1667 | |6;imaging-darkroom;97;105;105;97;97;105;98;96;99;100 15:49:44 INFO - PID 1667 | |7;imaging-desaturate;91;92;91;93;91;89;94;92;92;111 15:49:44 INFO - PID 1667 | |8;json-parse-financial;51;50;52;51;51;52;51;53;53;51 15:49:44 INFO - PID 1667 | |9;json-stringify-tinderbox;53;54;60;57;59;65;56;58;56;56 15:49:44 INFO - PID 1667 | |10;stanford-crypto-aes;78;82;79;82;79;79;80;101;99;76 15:49:44 INFO - PID 1667 | |11;stanford-crypto-ccm;135;134;134;128;152;130;139;147;150;166 15:49:44 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;178;182;181;174;209;214;175;179;180;182 15:49:44 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;64;64;76;64;67;64;63;67;66;76 15:49:44 INFO - PID 1667 | __end_tp_report 15:49:44 INFO - PID 1667 | __start_cc_report 15:49:44 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1011 15:49:44 INFO - PID 1667 | __end_cc_report 15:49:44 INFO - PID 1667 | __startTimestamp1497394184538__endTimestamp 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | ------- Summary: start ------- 15:49:44 INFO - PID 1667 | Number of tests: 14 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:121.30 Median:115.50 stddev:19.38 (16.8%) stddev-sans-first:19.39 15:49:44 INFO - PID 1667 | Values: 103.0 126.0 152.0 159.0 115.0 114.0 115.0 101.0 112.0 116.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:141.00 stddev:26.05 (18.5%) stddev-sans-first:27.55 15:49:44 INFO - PID 1667 | Values: 142.0 119.0 186.0 169.0 110.0 129.0 111.0 146.0 140.0 109.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:228.40 Median:234.00 stddev:41.81 (17.9%) stddev-sans-first:41.82 15:49:44 INFO - PID 1667 | Values: 268.0 280.0 195.0 278.0 281.0 194.0 196.0 199.0 200.0 193.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:82.70 Median:65.00 stddev:31.54 (48.5%) stddev-sans-first:33.00 15:49:44 INFO - PID 1667 | Values: 68.0 77.0 80.0 145.0 62.0 57.0 59.0 62.0 135.0 82.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:69.00 stddev:20.27 (29.4%) stddev-sans-first:20.93 15:49:44 INFO - PID 1667 | Values: 69.0 71.0 108.0 68.0 69.0 72.0 109.0 69.0 70.0 117.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:93.00 stddev:8.49 (9.1%) stddev-sans-first:8.90 15:49:44 INFO - PID 1667 | Values: 93.0 94.0 94.0 114.0 93.0 93.0 92.0 92.0 111.0 90.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:97.00 stddev:3.70 (3.8%) stddev-sans-first:3.77 15:49:44 INFO - PID 1667 | Values: 97.0 105.0 105.0 97.0 97.0 105.0 98.0 96.0 99.0 100.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:92.00 stddev:6.26 (6.8%) stddev-sans-first:6.57 15:49:44 INFO - PID 1667 | Values: 91.0 92.0 91.0 93.0 91.0 89.0 94.0 92.0 92.0 111.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.97 (1.9%) stddev-sans-first:1.01 15:49:44 INFO - PID 1667 | Values: 51.0 50.0 52.0 51.0 51.0 52.0 51.0 53.0 53.0 51.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:57.40 Median:57.50 stddev:3.41 (5.9%) stddev-sans-first:3.22 15:49:44 INFO - PID 1667 | Values: 53.0 54.0 60.0 57.0 59.0 65.0 56.0 58.0 56.0 56.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:79.50 stddev:8.89 (11.2%) stddev-sans-first:9.20 15:49:44 INFO - PID 1667 | Values: 78.0 82.0 79.0 82.0 79.0 79.0 80.0 101.0 99.0 76.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:143.00 stddev:11.97 (8.4%) stddev-sans-first:12.46 15:49:44 INFO - PID 1667 | Values: 135.0 134.0 134.0 128.0 152.0 130.0 139.0 147.0 150.0 166.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:181.50 stddev:14.06 (7.7%) stddev-sans-first:14.66 15:49:44 INFO - PID 1667 | Values: 178.0 182.0 181.0 174.0 209.0 214.0 175.0 179.0 180.0 182.0 15:49:44 INFO - PID 1667 | 15:49:44 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:66.50 stddev:4.89 (7.3%) stddev-sans-first:5.05 15:49:44 INFO - PID 1667 | Values: 64.0 64.0 76.0 64.0 67.0 64.0 63.0 67.0 66.0 76.0 15:49:44 INFO - PID 1667 | -------- Summary: end -------- 15:49:44 INFO - PID 1667 | 15:49:45 INFO - TEST-INFO | 1667: exit 0 15:49:45 INFO - TEST-OK | kraken | took 89064ms 15:49:45 INFO - SUITE-END | took 1232s 15:49:45 INFO - Completed test suite (00:20:33) 15:49:45 INFO - javascript benchmark 15:49:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8442.112011210314, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3554.342446647369, 3656.1042544576576, 3665.211979424425, 3668.1196512836927, 3688.9855289795305, 6248.468880145182, 6252.84367890803, 6271.874562508747, 6298.181236439263, 6333.873322533546, 6430.485561155108, 6431.485481161508, 6434.871302573945, 6443.548951573392, 6452.0321951707265, 5592.245046918675, 5606.495415412612, 5630.662160270376, 5658.0, 5953.58324917256, 5626.127950167731, 5685.488306052454, 5772.451617096369, 5785.334686511042, 5789.7684092636455, 4459.821607135711, 4684.882877928062, 5574.16387541872, 5588.385277619459, 5667.093265077609, 5264.657797243167, 5641.323041235078, 5661.179129026268, 5672.12082127271, 5691.573132015074, 4665.626749860003, 5517.531009864167, 5634.154876768476, 5638.210650508932, 5647.774089036434, 6384.648844313561, 6421.0, 6433.581817181868, 6458.38645328693, 6474.546781725281, 6732.528722989393, 6734.090897728793, 6807.2512023677355, 6816.0, 6979.511434199608, 5799.246098007232, 6962.060121883582, 7011.913153461222, 7038.260982596856, 7083.397911177556, 6319.652419116993, 6342.143810585558, 6441.227052753699, 6554.246261679968, 6566.3105373936005, 7149.392301654418, 7150.392216661642, 7187.712491500281, 7188.209296977328, 7210.495265331481, 5493.972530137324, 6721.1598550181225, 6749.8650026999185, 6752.392284694303, 6825.180978282638, 6864.073350097673, 6903.1371078615175, 6909.654517274116, 6924.93075069253, 6997.265287144878, 6955.6869940852785, 6956.7217311307995, 6966.825829354205, 6994.545354551938, 7000.579965202104, 6962.199347075049, 7011.5793052416, 7018.0, 7020.964895175492, 7038.155421349368, 5538.723063846792, 5546.77812887488, 5567.109262517978, 5578.135389014709, 5591.133374326986, 4852.199387101089, 4933.654644174873, 4943.480934501826, 4945.4807245238735, 4972.154733695281, 6254.74981000764, 6299.433051025429, 6335.081413195061, 6389.424951754364, 6396.840078998062, 6886.449084073362, 6939.861202775916, 6982.33667801558, 7077.681504332318, 7088.787336379917, 6472.870542589122, 6499.707513161919, 6500.252470965899, 6507.089007538948, 6533.954567269309, 6105.908411373833, 6155.322914479404, 6196.504279657616, 6226.159468471698, 6226.25284965807, 7092.4680648951535, 7177.174624917992, 7212.206657267697, 7217.206107328189, 7229.1325040993315, 6839.418649414855, 6861.519693621598, 6956.165260168812, 7035.788926332219, 7081.964590177222, 7122.821929451805, 7188.209296977433, 7194.568325900463, 7237.131544214728, 7261.745838895613, 6954.547954382949, 6992.930070699432, 6992.965035174791, 6999.0, 7006.964965175345, 7080.398166155935, 7113.359797618239, 7130.180029296697, 7142.357187853118, 7150.356467917913, 6814.352636499525, 6867.2102708189195, 6880.208775990825, 6894.586324820527, 6915.619640919797, 6606.570572912745, 6675.365840245169, 6718.328167183243, 6791.422729068085, 6880.827979300558, 6684.331566843277, 6689.632070236184, 6736.157980252468, 6737.3599508046655, 6740.561863478858, 6861.588304701734, 6895.4138898194215, 6902.482313826584, 6907.378335949788, 6909.861802764017, 6461.515386346136, 6695.330466953461, 6707.061011458302, 6730.293319201413, 6736.427403670743], "value": 6334.820877393781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.3052218864718, 772.5943879463281, 806.2260230179028, 861.1388611388611, 1205.7046023724188, 33617.82337618184, 37415.81292093536, 37428.812855935714, 37496.0, 37511.81244093783, 31039.379212415737, 37014.0, 37112.25775484489, 37209.62790372095, 37233.06917327061, 7757.495762775401, 7888.290053895148, 7894.289513943744, 7902.407319451036, 7908.52548847071, 7697.153313135551, 7789.922100779005, 7807.453478256524, 7983.12185659577, 8139.064007639108, 8237.258646721793, 8244.670213191495, 8288.58557072147, 8292.792680183013, 8302.958485207595, 8153.388495862804, 8204.220599043081, 8271.793205169859, 8274.2139496748, 8277.0, 8127.187281271885, 8155.796105097361, 8169.468984515987, 8198.057223419324, 8209.014918209794, 35241.64758352409, 35323.0, 35428.822855885555, 35527.75652852138, 35584.0, 3667.468217108504, 3696.77819330841, 3705.2589482103685, 3712.2761061593, 3730.664240218366, 29059.854700726362, 29142.41715165706, 29150.85424572885, 29160.270993225127, 29198.854005729834, 3141.5915930929064, 3163.31988622445, 3186.7291280240975, 3188.4260833050275, 3208.7593430492807, 249301.0, 250359.0, 323828.7617123822, 326188.0, 326859.0, 38797.22405551873, 38991.610083899366, 46805.0, 47345.526544734115, 47483.76258118687, 49345.753271233414, 49481.752591237535, 49557.25664115041, 49592.504074958786, 49644.0, 15211.543653690407, 15306.770398444032, 15416.845831541765, 15427.84572154264, 15439.536813895602, 12380.876191238152, 12553.372331383576, 12614.747705045847, 12616.24302541832, 12646.683832904251, 13147.34263286851, 13382.397792099378, 13408.396622152026, 13415.731685366238, 13469.326533673277, 5709.65742055478, 5782.017057100303, 5804.071348584291, 5804.883902322014, 5820.476157145878, 923.4828496042238, 966.1884017425256, 986.8322385194534, 1007.4610083605236, 1047.512906498482, 22189.11243550272, 22770.975306111264, 23241.535169296858, 23319.692369371907, 24004.79976001228, 974.7075877236942, 1094.0, 1107.4739498738195, 1112.410422476089, 1171.6133675887106, 10603.257771956043, 12912.612621621367, 15264.46574369914, 15769.29038193284, 16354.264058117413, 321697.0, 326147.0, 326271.0, 326735.0, 326751.0, 55262.72368638131, 55561.0, 55800.72099639557, 55888.88222235532, 55891.720541397844, 1333.6665833541615, 1496.4388354367113, 1505.510709019573, 1511.4860947277978, 1521.8554237347655, 27567.0, 29090.418191635625, 29492.115236542937, 29605.0, 29613.0, 36804.63195368119, 36845.81577092098, 37053.81473092617, 37056.25887482181, 37069.25861482809, 297490.0, 297547.5122624373, 297767.5111624428, 298227.0, 298966.0, 27784.444311114068, 28398.43203135967, 28411.573826392207, 33180.0, 33253.50119748157, 35045.0, 35176.0, 39112.022199445244, 39495.407568885916, 39658.603413966644, 23453.413664657837, 26976.460470790866, 27689.0, 29574.704252957195, 30037.5494367589, 28856.855715721285, 28924.566131508465, 28953.565696514146, 29644.962426314974, 29797.553036704034, 33351.83324083364, 33496.83251583727, 33591.16022099369, 34336.656633434344, 35262.0, 28415.28961775972, 28499.857500712365, 29161.833526659546, 29656.703432965394, 29807.0, 16962.74555881638, 17904.55238619045, 18254.45236642903, 18260.0, 18269.634607307515], "value": 17059.370164080454, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.3104989091199, 525.1780962793229, 525.4062908912928, 526.4735264735264, 548.0600769679997, 20536.97315134245, 22591.322260332196, 22815.885920570374, 23112.07551697934, 23293.76706232937, 23588.764112358873, 23722.406939826513, 24190.395240119007, 24228.75771242287, 24297.878510607465, 6162.907556386658, 6370.745170193187, 6374.776882809103, 6376.234851817789, 6379.87240255197, 6053.182820319266, 6250.624962502242, 6326.114343991845, 6350.333215012426, 6369.394907483782, 6325.304216536178, 6333.841653958665, 6343.048542718583, 6350.26971898233, 6354.904676429857, 6305.054241863734, 6341.302456729756, 6345.460635845959, 6355.364463553654, 6358.459530939876, 5805.854853628651, 5811.244538210006, 5817.243758311392, 5819.67991760453, 5821.7380217890295, 27286.181414557595, 29019.0, 29266.414671706447, 29834.80660773588, 30063.098107056823, 2241.3276017194776, 2287.0737351372723, 2292.839501234914, 2301.976980230193, 2302.9424264393356, 18589.163487643087, 19022.80977190224, 19029.333973310866, 19085.0457477127, 19148.94680792556, 2161.200355868323, 2180.062573093569, 2219.578280126787, 2258.6499092640665, 2262.7171603549555, 140900.0, 170917.0, 183904.08047959674, 186926.0, 220890.0, 14297.213653248964, 14581.0, 14915.925420372829, 15186.316615752097, 15237.695246095016, 20263.696044559343, 20354.0, 20375.08312125958, 20395.4901127473, 20639.277625283044, 10225.386476811267, 10411.427371494488, 10422.374657520422, 10434.269601127848, 10440.843387349196, 8857.778555536162, 9328.0, 9852.704418867446, 9889.45607991553, 9934.205263578897, 8792.692255771017, 8936.553172341357, 8982.775430614156, 8992.8651070234, 9003.954980225057, 2242.6299660555824, 2647.2984659065364, 2648.8808003639883, 2665.3469899874267, 2758.6413766210667, 705.2594775485719, 932.7901222225081, 934.7429456899271, 951.3578334624101, 991.8214721350226, 20592.27927022547, 21168.0, 21320.36038918835, 21507.89246053791, 21631.783682162975, 902.7291812456236, 958.4281777884275, 972.2756546372998, 995.5719040812376, 1025.6102680981328, 12621.558245461549, 12859.742805143846, 14219.217943013233, 14483.275836208148, 14561.490347837775, 580209.0, 599891.0, 600181.0, 606244.0, 617365.0, 164978.1751091237, 166646.0, 166848.0, 167305.0, 169967.0, 1022.9693109206736, 1089.9073578745895, 1098.4123493930708, 1100.339796122336, 1179.2747460312019, 19783.109760060543, 21430.78569214288, 23031.88484057569, 24100.87949560241, 24463.899124539796, 71710.92433613604, 73563.89654155084, 74606.8808967855, 76036.0, 78157.21842781726, 94843.52578237065, 98423.52364714391, 98824.0, 99965.00069998705, 100459.49310760491, 11550.075993920635, 11880.34658093813, 12001.279923204636, 12034.879651203375, 12090.335031573348, 18403.631927361646, 18487.075646217905, 18656.0, 18674.533136671147, 18784.53038674044, 12068.577599783965, 14675.48635797742, 15301.923490382922, 15339.38642454334, 15527.068375897483, 13739.175649461064, 13876.306184690524, 14055.437782488585, 14149.0, 14220.786688199893, 13907.930460347634, 15676.372945082068, 15812.209389530248, 15954.122523261332, 15973.68052638964, 11653.30080195191, 11751.882481175078, 11824.940875295568, 11858.11064170208, 11919.880801191877, 14746.926265368604, 14865.256737162883, 14941.0, 14955.551333459578, 15054.397824086915], "value": 11904.866999001004, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1091.4433638844187, 1294.2622705058118, 1451.3468938977462, 1452.949146779863, 1476.778483227516, 13578.660533486667, 14175.291235438213, 14187.077839940397, 14208.076475029116, 14209.360578773954, 13648.727025459484, 14091.718165636681, 14106.0, 14161.07952983058, 14199.574012779607, 2828.830270183785, 2841.3891013432108, 2857.3285277959662, 2877.6115224444643, 2878.7840911931585, 2399.9760002400017, 2817.7464028237455, 2856.9285767855868, 2870.2967772895568, 2870.5550639650887, 2805.116388337677, 2842.403095349979, 2847.857607119646, 2850.643669541307, 2851.5580085086845, 2788.5538313869783, 2802.6776920654183, 2807.887684492618, 2811.170704642125, 2813.563897595876, 2726.2366537369567, 2760.3789147441867, 2770.1551026936777, 2770.501309764242, 2772.029789573633, 13613.387397567034, 13697.863021369758, 13932.024758266925, 13957.162570245717, 13963.720725585432, 6696.8615335392615, 6744.494162937799, 7710.57591832449, 7940.920590794076, 8020.598970051533, 4161.25097482453, 4687.88280292992, 4880.389951256093, 5011.398632164127, 5012.548870601663, 1206.6500714792699, 1410.9294535273298, 1420.5596265157835, 1428.19307091494, 1435.519101101122, 16213.2704028319, 19118.139683714126, 20842.895785520974, 21000.05499752515, 21033.0, 6165.1985241919165, 6844.931550684529, 7081.787546373617, 7131.322524360177, 7161.176464706521, 12291.385430728427, 14716.632084197767, 14740.11559306445, 14747.262636868114, 14770.039947403384, 7585.924140758632, 7734.922650773533, 7759.495632783851, 7761.495502792301, 7801.921980780233, 6984.406325462291, 7159.821004474929, 7233.1681856587165, 7239.601821899741, 7261.636918154177, 4382.561743825592, 5358.705271210043, 5372.489613486712, 5437.728113594305, 5439.891202175934, 1573.417835400909, 1575.5903465099157, 1587.103286643043, 1598.3606557376909, 1601.1673929556566, 811.3874025111004, 836.9037560680478, 846.7036537211926, 848.3510114762212, 869.8956125265008, 7178.353948144692, 7216.097987751531, 7499.887501687479, 7560.659770310349, 7575.24247575238, 703.8662654095706, 707.2785758526275, 720.6901032556051, 721.487743701967, 724.655788500458, 4206.915861682749, 4210.326347784401, 4394.406755088086, 4795.064962332312, 5157.406898206679, 18366.449006529827, 18929.905350473437, 18976.52558686044, 18978.52553686169, 18982.0, 17824.732629010574, 18825.623487530174, 18887.150078246512, 18919.52701182454, 18922.432327029936, 9291.67479138227, 9638.614455421844, 9754.0, 9803.313768036169, 9829.459379734184, 7507.942534018006, 7752.573608451364, 7855.567943763148, 7880.645370958206, 8184.386171037077, 16961.66076678484, 17419.39032133869, 17447.912760436626, 17458.563535911704, 17741.645167096845, 16481.83518164851, 16930.0, 18085.728714069035, 18161.636767264317, 18186.545336366697, 10908.781824363627, 11016.944915275373, 11225.550977960791, 11333.773324533298, 11480.0, 13142.868571314164, 13221.074524783173, 13338.866611333762, 13346.666333341744, 13365.799513007507, 10168.389896606228, 10266.281360304707, 10403.791924161626, 10530.05229529346, 10703.304285221435, 8488.193621605938, 9095.681651142178, 10004.649837255662, 10064.144547713233, 10333.225008124271, 9353.625854965725, 9424.481653509123, 9512.571934262836, 9517.000714924834, 9520.809583808423, 6162.198914141133, 6576.506761992774, 6929.203141638574, 7012.088428504466, 7178.641067946686, 5609.915851262316, 7039.788806335818, 7227.096612923385, 7403.51877127985, 7435.182129965591], "value": 6182.118163358414, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.74886940009503, 450.2570758248889, 465.8113464047061, 477.3817905811972, 480.66593717366425, 5704.001799685049, 6193.442590166884, 6389.78594067127, 6642.634655093968, 6644.807213619506, 4978.5270399312, 5870.647761134335, 6367.586106903048, 6512.902306465407, 6522.608643481394, 4852.514748525137, 4858.805647774085, 4860.513948605147, 4872.926906096412, 4895.9510404895855, 4013.2776100301935, 4126.814293356806, 4810.350602668647, 4822.541858523435, 4856.684315519497, 4642.257238841786, 4786.353842231307, 4819.650343011608, 4843.418789745236, 4860.878478038042, 3737.8691745788765, 4087.0395457067566, 4349.391085248068, 4494.662900282476, 4840.104580652555, 3555.839987200582, 4246.278132717445, 4255.446791917031, 4258.48898132223, 4259.744415335059, 5296.708681022542, 5743.5118014968775, 6211.937880621226, 6294.213223347082, 6325.778597749057, 4895.926561101586, 5737.540996720294, 5746.511546518551, 5756.769729210827, 5760.222369980041, 5929.73316200772, 6284.874302513924, 6908.171019477644, 6993.2657071007325, 7027.262137475543, 4847.248676455155, 4858.708477491326, 4875.780589873464, 4879.731614761186, 4886.975565122188, 7359.153697324823, 7373.88939165913, 7400.555966642019, 7402.33378995893, 7429.0, 4242.918055895727, 4936.876578085505, 4954.455009948902, 4968.90062198754, 4990.6257030722845, 4643.674942754043, 4978.5270399312, 5042.873928151826, 5044.848654540369, 5049.091163590589, 5734.397888221677, 5751.683657398883, 5756.913646295309, 5770.596058275964, 5773.546242846774, 7002.719891204397, 7009.12385951756, 7056.823579410556, 7059.858802823915, 7095.467839912028, 1591.3873158833933, 1715.7340612205128, 1717.4761697682327, 1721.2254485481583, 1722.7071397862394, 1055.8680164979378, 1095.9040959040958, 1143.7883991461606, 1154.7459558897028, 1161.5702190189684, 6254.312025677172, 6470.38531339522, 6609.926384231259, 6633.834154146185, 6642.634655094016, 6035.78874739382, 7211.711531538785, 7249.420046396275, 7255.0, 7281.927180728231, 6518.706658200393, 6537.771178008747, 6540.247871494744, 6555.0, 6601.636909969901, 3151.700588444094, 3378.7803792753393, 3408.9659103409144, 3725.4598083278315, 3790.6209379062425, 7298.8175295616975, 7385.4460915431555, 7387.8153046174275, 7395.334419902235, 7407.703691852265, 6980.917957716562, 7111.90198891318, 7352.338289553966, 7377.483576149618, 7378.483506154625, 6212.875742485216, 6818.772620928082, 6914.30599503125, 7163.928360716431, 7472.85054298911, 6678.332166783282, 7250.456215783943, 7258.600776957318, 7259.382952449101, 7269.45479089081, 5909.724755968897, 6705.765298214539, 7022.613756243456, 7079.964600176966, 7108.0, 5794.681292528951, 6503.317151699192, 7311.707531698672, 7401.33387995083, 7484.43866709988, 4837.612990960785, 4875.536824001714, 4877.5366340197625, 4917.557419832089, 4928.605711542997, 6578.8355291118105, 7136.0, 7153.2131465537705, 7164.9641751793, 7238.674259658434, 6706.893362595117, 6866.931330686829, 6910.4391101872525, 6935.7225710971, 6963.338482844122, 6056.84857878539, 6092.45167934888, 6640.900386494303, 6652.501062420434, 6913.75801846933, 6770.93229067703, 6802.3537763912345, 6825.829354265985, 6837.658117094026, 6849.520533562602, 6369.522285828489, 6373.362663733589, 6967.1291088613925, 6980.11392025574, 7001.8949715755325, 6217.96891015542, 6772.288909664414, 6781.321867813179, 6804.829879253059, 6858.348456896587], "value": 5098.4148513972705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.795339619722, 775.3796962430056, 812.731798506493, 822.5558198572771, 858.9441686290389, 16544.66910661786, 16963.98216107033, 17104.401345952894, 17269.82730172698, 17289.221985010674, 17518.474445766617, 18387.72418413725, 18597.814021859813, 18676.159572819222, 18746.718799218022, 995.7809281957958, 1004.5027711282925, 1016.6086056868122, 1019.1642852860657, 1019.8419245017034, 1012.185190921308, 1013.002192840052, 1013.1843865688118, 1015.6597539824131, 1016.6289304403876, 1009.5457044330044, 1052.41590917041, 1057.746140926176, 1060.1624716473975, 1064.435849000031, 1028.198005555664, 1039.2880876599554, 1040.6149724601871, 1043.2332235806714, 1045.8744950605899, 758.3137260778949, 760.68811787167, 760.6995236881401, 762.6339357108619, 764.2319468933686, 17453.12734363277, 17582.0, 17614.823851761575, 17775.377861774778, 17791.46625601234, 14872.0, 17606.471805845846, 17790.46628601144, 18079.27682892696, 18164.545886352815, 14771.630709232248, 14778.26108694561, 14855.92572037133, 14940.477083302141, 14969.775453368207, 3560.359135355635, 3585.4980302757635, 3591.676749092568, 3600.027992442052, 3852.595477474853, 146093.26953365165, 150982.0, 152460.0, 153351.0, 158611.2069439657, 13523.918086553052, 13833.308334583231, 13876.375563099684, 14113.0, 14177.14937103777, 27411.451770964468, 27417.588736168975, 27580.586291205647, 27620.447591048065, 27649.0, 8059.073206581201, 8135.511869287861, 8163.673453061811, 8194.385421093442, 8223.917760822435, 4126.504819421689, 4129.855455059059, 4148.315527937856, 4197.223513649985, 4215.641670458045, 4300.290452075373, 4488.685791994594, 4494.640428765691, 4497.0, 4516.638668906479, 768.8270139218743, 905.7509184974185, 908.6592527802075, 984.2863923655293, 993.0764389118045, 559.9076152434884, 593.4599514441837, 597.9774585458914, 598.853280946171, 622.8972219583718, 9744.658936937172, 12247.142700011093, 12265.2640841548, 12539.561115360919, 12947.546835860889, 591.6982339007051, 591.9852003699942, 593.6794131169206, 607.9939200607937, 621.4034526854181, 5132.8460146195675, 5545.77816887328, 5692.576855786054, 5740.626859254218, 6023.367546407651, 142942.8558571622, 143448.0, 147120.0, 151347.0, 153340.23329883278, 113109.4344528272, 120517.0, 122101.0, 125613.60352387623, 127493.72506274818, 107568.46215769027, 108046.45976770065, 109107.0, 109979.45010274898, 111808.4409577947, 10729.195310351599, 10890.128789696646, 11774.94112529432, 11821.290722556674, 11997.700057498632, 41248.79375603103, 42333.0, 42795.358069629605, 45189.870253243935, 48603.756981214865, 74831.0, 77957.0, 78305.82541261772, 80524.0, 85155.0, 17346.73979890328, 17456.30174792994, 17615.295388184328, 17649.470515884543, 17976.2809487619, 18128.546786330448, 18195.636087278446, 19332.90333548323, 19831.60336793285, 19885.403437896886, 17756.822431775516, 17781.666375022185, 18013.64897632709, 18063.819361806738, 18156.636867262845, 14310.71378572402, 17043.940116149144, 18014.2794288227, 18084.72872906934, 18248.908755456137, 16437.67124657524, 17030.57423564421, 17613.03128327903, 17840.3755868544, 17992.0, 11992.160548761503, 12005.39973001364, 12006.159568830099, 12228.816567751668, 12906.41921113571, 13621.659458513617, 14147.504837330644, 14251.429942802586, 14260.643483912985, 14262.286885655883], "value": 8926.606659784706, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 126.0, 152.0, 159.0, 115.0, 114.0, 115.0, 101.0, 112.0, 116.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 119.0, 186.0, 169.0, 110.0, 129.0, 111.0, 146.0, 140.0, 109.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [268.0, 280.0, 195.0, 278.0, 281.0, 194.0, 196.0, 199.0, 200.0, 193.0], "value": 228.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 80.0, 145.0, 62.0, 57.0, 59.0, 62.0, 135.0, 82.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 108.0, 68.0, 69.0, 72.0, 109.0, 69.0, 70.0, 117.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 114.0, 93.0, 93.0, 92.0, 92.0, 111.0, 90.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 105.0, 105.0, 97.0, 97.0, 105.0, 98.0, 96.0, 99.0, 100.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 93.0, 91.0, 89.0, 94.0, 92.0, 92.0, 111.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 52.0, 51.0, 51.0, 52.0, 51.0, 53.0, 53.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 60.0, 57.0, 59.0, 65.0, 56.0, 58.0, 56.0, 56.0], "value": 57.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 79.0, 82.0, 79.0, 79.0, 80.0, 101.0, 99.0, 76.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 134.0, 128.0, 152.0, 130.0, 139.0, 147.0, 150.0, 166.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 182.0, 181.0, 174.0, 209.0, 214.0, 175.0, 179.0, 180.0, 182.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 76.0, 64.0, 67.0, 64.0, 63.0, 67.0, 66.0, 76.0], "value": 67.1, "unit": "score"}]}]} 15:49:45 INFO - Return code: 0 15:49:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:49:45 INFO - # TBPL SUCCESS # 15:49:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:49:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:49:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:49:45 INFO - 2017-06-13 15:49:45.943 screenresolution[1675:10947] starting screenresolution argv=screenresolution get 15:49:45 INFO - 2017-06-13 15:49:45.945 screenresolution[1675:10947] Display 0: 1600x1200x32@60 15:49:45 INFO - 2017-06-13 15:49:45.951 screenresolution[1676:10950] starting screenresolution argv=screenresolution list 15:49:45 INFO - Available Modes on Display 0 15:49:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:49:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:49:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:49:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:49:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:49:46 INFO - Intel Iris: 15:49:46 INFO - Chipset Model: Intel Iris 15:49:46 INFO - Type: GPU 15:49:46 INFO - Bus: Built-In 15:49:46 INFO - VRAM (Dynamic, Max): 1536 MB 15:49:46 INFO - Vendor: Intel (0x8086) 15:49:46 INFO - Device ID: 0x0a2e 15:49:46 INFO - Revision ID: 0x0009 15:49:46 INFO - Displays: 15:49:46 INFO - Display: 15:49:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:49:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:49:46 INFO - Display Serial Number: 1600x1200 60 15:49:46 INFO - Main Display: Yes 15:49:46 INFO - Mirror: Off 15:49:46 INFO - Online: Yes 15:49:46 INFO - Rotation: Supported 15:49:46 INFO - Return code: 0 15:49:46 INFO - Running post-action listener: _resource_record_post_action 15:49:46 INFO - [mozharness: 2017-06-13 22:49:46.189719Z] Finished run-tests step (success) 15:49:46 INFO - Running post-run listener: _resource_record_post_run 15:49:46 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 51010048; Write bytes: 562268160; Read time: 613; Write time: 2033 15:49:46 INFO - TinderboxPrint: CPU usage
53.2% 15:49:46 INFO - TinderboxPrint: I/O read bytes / time
51,010,048 / 613 15:49:46 INFO - TinderboxPrint: I/O write bytes / time
562,268,160 / 2,033 15:49:46 INFO - TinderboxPrint: CPU idle
2,350.4 (46.8%) 15:49:46 INFO - TinderboxPrint: CPU system
1,016.7 (20.2%) 15:49:46 INFO - TinderboxPrint: CPU user
1,654.5 (32.9%) 15:49:46 INFO - TinderboxPrint: Swap in / out
284,766,208 / 0 15:49:46 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230007296; Write bytes: 228235264; Read time: 16791; Write time: 490 15:49:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:49:46 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 49121792; Write bytes: 314724352; Read time: 591; Write time: 1493 15:49:46 INFO - Running post-run listener: _upload_blobber_files 15:49:46 INFO - Blob upload gear active. 15:49:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:46 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 15:49:46 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'] 15:49:46 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 15:49:47 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:48 INFO - (blobuploader) - INFO - Done attempting. 15:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:49 INFO - (blobuploader) - INFO - Done attempting. 15:49:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:49:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:49:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:50 INFO - (blobuploader) - INFO - Done attempting. 15:49:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:52 INFO - (blobuploader) - INFO - Done attempting. 15:49:52 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:52 INFO - Return code: 0 15:49:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/464113ddebc8c3a8f66ccec1b2366c24cc2551202dee11626619edddc82e7e6a8ce56b6aa4cd7395b80bd68568cffe6a24315b0699146454e4be023e000b6578", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/47328d69fa2c01732ee30f67576537dbf098363bed6a5e3c345fa712100abc864d07f0c8dc7312cf332a34f33957db81315960f869b117e38df404c70065925f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0310e05d5971312d6c79e9a7af1a3b335ea23f4daeaba07f8e5e972c598954ef589017b6b2bbc4dfc5ca18df6eb9faa4f72ca4535895b7cfe979174a21f2b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd73e7a7c1eefc0252f8ee9da4b90083df682ee16b01e665c1ffb343829090b7279b3f21d334f7b944b5479e52c89c1dbb87c22450d1381895d6ea06b70a62f8"} 15:49:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:52 INFO - Contents: 15:49:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/464113ddebc8c3a8f66ccec1b2366c24cc2551202dee11626619edddc82e7e6a8ce56b6aa4cd7395b80bd68568cffe6a24315b0699146454e4be023e000b6578", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/47328d69fa2c01732ee30f67576537dbf098363bed6a5e3c345fa712100abc864d07f0c8dc7312cf332a34f33957db81315960f869b117e38df404c70065925f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0310e05d5971312d6c79e9a7af1a3b335ea23f4daeaba07f8e5e972c598954ef589017b6b2bbc4dfc5ca18df6eb9faa4f72ca4535895b7cfe979174a21f2b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd73e7a7c1eefc0252f8ee9da4b90083df682ee16b01e665c1ffb343829090b7279b3f21d334f7b944b5479e52c89c1dbb87c22450d1381895d6ea06b70a62f8"} 15:49:52 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:52 INFO - Copying logs to upload dir... 15:49:52 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.359096 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-13 15:49:53.018040) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 15:49:53.021342) ========= 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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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/464113ddebc8c3a8f66ccec1b2366c24cc2551202dee11626619edddc82e7e6a8ce56b6aa4cd7395b80bd68568cffe6a24315b0699146454e4be023e000b6578", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/47328d69fa2c01732ee30f67576537dbf098363bed6a5e3c345fa712100abc864d07f0c8dc7312cf332a34f33957db81315960f869b117e38df404c70065925f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0310e05d5971312d6c79e9a7af1a3b335ea23f4daeaba07f8e5e972c598954ef589017b6b2bbc4dfc5ca18df6eb9faa4f72ca4535895b7cfe979174a21f2b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd73e7a7c1eefc0252f8ee9da4b90083df682ee16b01e665c1ffb343829090b7279b3f21d334f7b944b5479e52c89c1dbb87c22450d1381895d6ea06b70a62f8"} build_url:https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011063 build_url: 'https://queue.taskcluster.net/v1/task/PcSwo5jVScmetfbsx-jBOg/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/464113ddebc8c3a8f66ccec1b2366c24cc2551202dee11626619edddc82e7e6a8ce56b6aa4cd7395b80bd68568cffe6a24315b0699146454e4be023e000b6578", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/47328d69fa2c01732ee30f67576537dbf098363bed6a5e3c345fa712100abc864d07f0c8dc7312cf332a34f33957db81315960f869b117e38df404c70065925f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0310e05d5971312d6c79e9a7af1a3b335ea23f4daeaba07f8e5e972c598954ef589017b6b2bbc4dfc5ca18df6eb9faa4f72ca4535895b7cfe979174a21f2b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cd73e7a7c1eefc0252f8ee9da4b90083df682ee16b01e665c1ffb343829090b7279b3f21d334f7b944b5479e52c89c1dbb87c22450d1381895d6ea06b70a62f8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 15:49:53.059650) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:49:53.060390) ========= 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.HRE4zpbwL5/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.gcDF2MWpjt/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.005125 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:49:53.112619) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 15:49:53.113279) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 15:49:53.113935) ========= ========= Total master_lag: 0.20 =========