builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0005 starttime: 1494550871.49 results: success (0) revision: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.486521) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.487015) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.487360) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.511622) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.512087) ========= 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.FqyAsb5yki/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.NhT9SoXwUV/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-05-11 18:01:11-- 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% 7.82M=0.001s 2017-05-11 18:01:11 (7.82 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.614711) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.615063) ========= 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.FqyAsb5yki/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.NhT9SoXwUV/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.022381 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:11.663681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-11 18:01:11.664032) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c1719e0d676b56ccd940c6f8a9b1bb819d441b20 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev c1719e0d676b56ccd940c6f8a9b1bb819d441b20 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FqyAsb5yki/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.NhT9SoXwUV/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-05-11 18:01:11,746 truncating revision to first 12 chars 2017-05-11 18:01:11,746 Setting DEBUG logging. 2017-05-11 18:01:11,746 attempt 1/10 2017-05-11 18:01:11,746 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c1719e0d676b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 18:01:13,785 unpacking tar archive at: mozilla-central-c1719e0d676b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.292894 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-11 18:01:13.979251) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:13.979573) ========= script_repo_revision: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:13.979953) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:13.980234) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 18:01:13.999216) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-11 18:01:13.999565) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FqyAsb5yki/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.NhT9SoXwUV/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:01:14 INFO - MultiFileLogger online at 20170511 18:01:14 in /builds/slave/test 18:01:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:01:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:14 INFO - {'append_to_log': False, 18:01:14 INFO - 'base_work_dir': '/builds/slave/test', 18:01:14 INFO - 'blob_upload_branch': 'Firefox', 18:01:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:14 INFO - 'branch': 'Firefox', 18:01:14 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:14 INFO - 'config_files': ('talos/mac_config.py',), 18:01:14 INFO - 'default_actions': ('clobber', 18:01:14 INFO - 'read-buildbot-config', 18:01:14 INFO - 'download-and-extract', 18:01:14 INFO - 'populate-webroot', 18:01:14 INFO - 'create-virtualenv', 18:01:14 INFO - 'install', 18:01:14 INFO - 'run-tests'), 18:01:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:14 INFO - 'download_minidump_stackwalk': True, 18:01:14 INFO - 'download_symbols': 'ondemand', 18:01:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:14 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:14 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:14 INFO - 'gecko_profile': False, 18:01:14 INFO - 'gecko_profile_interval': 0, 18:01:14 INFO - 'installer_path': 'installer.exe', 18:01:14 INFO - 'log_level': 'info', 18:01:14 INFO - 'log_name': 'talos', 18:01:14 INFO - 'log_to_console': True, 18:01:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:01:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:01:14 INFO - 'opt_config_files': (), 18:01:14 INFO - 'pip_index': False, 18:01:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:14 INFO - 'cmd': ('bash', 18:01:14 INFO - '-c', 18:01:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:14 INFO - 'enabled': True, 18:01:14 INFO - 'halt_on_failure': False, 18:01:14 INFO - 'name': 'check_screen_resolution'},), 18:01:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:14 INFO - 'cmd': ('bash', 18:01:14 INFO - '-c', 18:01:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:14 INFO - 'enabled': True, 18:01:14 INFO - 'halt_on_failure': False, 18:01:14 INFO - 'name': 'check_screen_resolution'},), 18:01:14 INFO - 'run_cmd_checks_enabled': True, 18:01:14 INFO - 'suite': 'dromaeojs-e10s', 18:01:14 INFO - 'system_bits': '32', 18:01:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:14 INFO - 'title': 't-yosemite-r7-0005', 18:01:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:14 INFO - 'use_talos_json': True, 18:01:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:14 INFO - 'work_dir': 'build'} 18:01:14 INFO - [mozharness: 2017-05-12 01:01:14.208198Z] Running clobber step. 18:01:14 INFO - Running pre-action listener: _resource_record_pre_action 18:01:14 INFO - Running main action method: clobber 18:01:14 INFO - rmtree: /builds/slave/test/build 18:01:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:16 INFO - Running post-action listener: _resource_record_post_action 18:01:16 INFO - [mozharness: 2017-05-12 01:01:16.357544Z] Finished clobber step (success) 18:01:16 INFO - [mozharness: 2017-05-12 01:01:16.357695Z] Running read-buildbot-config step. 18:01:16 INFO - Running pre-action listener: _resource_record_pre_action 18:01:16 INFO - Running main action method: read_buildbot_config 18:01:16 INFO - Using buildbot properties: 18:01:16 INFO - { 18:01:16 INFO - "project": "", 18:01:16 INFO - "product": "firefox", 18:01:16 INFO - "who": "kwierso@gmail.com", 18:01:16 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:01:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:01:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:01:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:01:16 INFO - "stage_platform": "macosx64", 18:01:16 INFO - "basedir": "/builds/slave/test", 18:01:16 INFO - "buildnumber": 72, 18:01:16 INFO - "platform": "yosemite_r7", 18:01:16 INFO - "parent_task_id": "BAvyJcF9RI-S6FkExrIenA", 18:01:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:01:16 INFO - "slavebuilddir": "test", 18:01:16 INFO - "taskId": "RjGbqeJJRzyboj4vr9NQ0w", 18:01:16 INFO - "branch": "mozilla-central", 18:01:16 INFO - "script_repo_revision": "production", 18:01:16 INFO - "revision": "c1719e0d676b56ccd940c6f8a9b1bb819d441b20", 18:01:16 INFO - "slavename": "t-yosemite-r7-0005", 18:01:16 INFO - "repo_path": "mozilla-central" 18:01:16 INFO - } 18:01:16 INFO - Finding installer, test and symbols from parent task. 18:01:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjGbqeJJRzyboj4vr9NQ0w'}, attempt #1 18:01:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjGbqeJJRzyboj4vr9NQ0w'}, attempt #1 18:01:17 INFO - Task dependencies: F1Uu-HM1RAKUr6Fb-1v9vg 18:01:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA'}, attempt #1 18:01:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:18 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/test_packages.json 18:01:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/target.crashreporter-symbols.zip 18:01:18 INFO - Running post-action listener: _resource_record_post_action 18:01:18 INFO - [mozharness: 2017-05-12 01:01:18.171411Z] Finished read-buildbot-config step (success) 18:01:18 INFO - [mozharness: 2017-05-12 01:01:18.171553Z] Running download-and-extract step. 18:01:18 INFO - Running pre-action listener: _resource_record_pre_action 18:01:18 INFO - Running main action method: download_and_extract 18:01:18 INFO - mkdir: /builds/slave/test/build/tests 18:01:18 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')]} 18:01:18 INFO - trying https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:01:18 INFO - Downloading https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:01:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:01:19 INFO - Downloaded 1325 bytes. 18:01:19 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:01:19 INFO - Using the following test package requirements: 18:01:19 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:01:19 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:01:19 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:01:19 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:01:19 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'jsshell-mac.zip'], 18:01:19 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:01:19 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:01:19 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:01:19 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:01:19 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:01:19 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:01:19 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:01:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:01:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:01:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:19 INFO - Fetch https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:01:20 INFO - Content-Length response header: 52708119 18:01:20 INFO - Bytes received: 52708119 18:01:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:01:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:01:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:37 INFO - Fetch https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:01:39 INFO - Content-Length response header: 52708119 18:01:39 INFO - Bytes received: 52708119 18:01:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:01:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:01:54 INFO - Fetch https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:01:55 INFO - Content-Length response header: 14409411 18:01:55 INFO - Bytes received: 14409411 18:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:55 INFO - trying https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:55 INFO - Downloading https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:01:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:01:58 INFO - Downloaded 75525784 bytes. 18:01:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:58 INFO - mkdir: /builds/slave/test/properties 18:01:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:58 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:58 INFO - Contents: 18:01:58 INFO - build_url:https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:01:58 INFO - Running post-action listener: _resource_record_post_action 18:01:58 INFO - Running post-action listener: set_extra_try_arguments 18:01:58 INFO - [mozharness: 2017-05-12 01:01:58.181516Z] Finished download-and-extract step (success) 18:01:58 INFO - [mozharness: 2017-05-12 01:01:58.181656Z] Running populate-webroot step. 18:01:58 INFO - Running pre-action listener: _resource_record_pre_action 18:01:58 INFO - Running main action method: populate_webroot 18:01:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:58 INFO - '--tppagecycles', 18:01:58 INFO - '7'], 18:01:58 INFO - 'tests': ['tsvgm']}}, 18:01:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:01:58 INFO - 'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:01:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:01:58 INFO - 'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['damp', 'tps']}, 18:01:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:01:58 INFO - 'tests': ['damp', 'tps']}, 18:01:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['dromaeo_dom']}, 18:01:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:01:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:01:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['a11yr', 18:01:58 INFO - 'ts_paint', 18:01:58 INFO - 'tpaint', 18:01:58 INFO - 'sessionrestore', 18:01:58 INFO - 'sessionrestore_no_auto_restore', 18:01:58 INFO - 'tabpaint']}, 18:01:58 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:58 INFO - 'ts_paint', 18:01:58 INFO - 'tpaint', 18:01:58 INFO - 'sessionrestore', 18:01:58 INFO - 'sessionrestore_no_auto_restore', 18:01:58 INFO - 'tabpaint']}, 18:01:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:58 INFO - 'ts_paint', 18:01:58 INFO - 'tpaint', 18:01:58 INFO - 'sessionrestore', 18:01:58 INFO - 'sessionrestore_no_auto_restore', 18:01:58 INFO - 'tabpaint']}, 18:01:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:58 INFO - 'ts_paint', 18:01:58 INFO - 'tpaint', 18:01:58 INFO - 'sessionrestore', 18:01:58 INFO - 'sessionrestore_no_auto_restore', 18:01:58 INFO - 'tabpaint']}, 18:01:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['a11yr', 18:01:58 INFO - 'ts_paint', 18:01:58 INFO - 'tpaint', 18:01:58 INFO - 'sessionrestore', 18:01:58 INFO - 'sessionrestore_no_auto_restore', 18:01:58 INFO - 'tabpaint']}, 18:01:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['a11yr', 18:01:58 INFO - 'ts_paint', 18:01:58 INFO - 'tpaint', 18:01:58 INFO - 'sessionrestore', 18:01:58 INFO - 'sessionrestore_no_auto_restore', 18:01:58 INFO - 'tabpaint']}, 18:01:58 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:01:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:01:58 INFO - 'bloom_basic_ref']}, 18:01:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['tsvgx', 18:01:58 INFO - 'tsvgr_opacity', 18:01:58 INFO - 'tart', 18:01:58 INFO - 'tscrollx', 18:01:58 INFO - 'cart', 18:01:58 INFO - 'tsvg_static']}, 18:01:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:58 INFO - 'tsvgr_opacity', 18:01:58 INFO - 'tart', 18:01:58 INFO - 'tscrollx', 18:01:58 INFO - 'cart', 18:01:58 INFO - 'tsvg_static']}, 18:01:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:01:58 INFO - 'talos_options': ['--disable-e10s'], 18:01:58 INFO - 'tests': ['tp5o']}, 18:01:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:01:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:01:58 INFO - 'talos_options': ['--disable-e10s', 18:01:58 INFO - '--xperf_path', 18:01:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:58 INFO - 'tests': ['tp5n']}, 18:01:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:01:58 INFO - 'talos_options': ['--xperf_path', 18:01:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:58 INFO - 'tests': ['tp5n']}}, 18:01:58 INFO - 'talos.zip': {'path': '', 18:01:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:58 INFO - Running post-action listener: _resource_record_post_action 18:01:58 INFO - [mozharness: 2017-05-12 01:01:58.202700Z] Finished populate-webroot step (success) 18:01:58 INFO - [mozharness: 2017-05-12 01:01:58.202813Z] Running create-virtualenv step. 18:01:58 INFO - Running pre-action listener: _resource_record_pre_action 18:01:58 INFO - Running main action method: create_virtualenv 18:01:58 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:58 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 18:01:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:58 INFO - Using real prefix '/tools/python27' 18:01:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:59 INFO - Installing distribute.............................................................................................................................................................................................done. 18:02:01 INFO - Installing pip.................done. 18:02:01 INFO - Return code: 0 18:02:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:02:02 INFO - Reading from file tmpfile_stdout 18:02:02 INFO - Output received: 18:02:02 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) 18:02:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:02 INFO - 'HOME': '/Users/cltbld', 18:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:02 INFO - 'LOGNAME': 'cltbld', 18:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:02 INFO - 'MOZ_NO_REMOTE': '1', 18:02:02 INFO - 'NO_EM_RESTART': '1', 18:02:02 INFO - 'PAGER': '/bin/cat', 18:02:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:02 INFO - 'PWD': '/builds/slave/test', 18:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:02 INFO - 'SHELL': '/bin/bash', 18:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:02 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:02 INFO - 'USER': 'cltbld', 18:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:02 INFO - 'XPC_FLAGS': '0x0', 18:02:02 INFO - 'XPC_SERVICE_NAME': '0', 18:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:02 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 18:02:02 INFO - Cleaning up... 18:02:02 INFO - Return code: 0 18:02:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:02 INFO - 'HOME': '/Users/cltbld', 18:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:02 INFO - 'LOGNAME': 'cltbld', 18:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:02 INFO - 'MOZ_NO_REMOTE': '1', 18:02:02 INFO - 'NO_EM_RESTART': '1', 18:02:02 INFO - 'PAGER': '/bin/cat', 18:02:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:02 INFO - 'PWD': '/builds/slave/test', 18:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:02 INFO - 'SHELL': '/bin/bash', 18:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:02 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:02 INFO - 'USER': 'cltbld', 18:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:02 INFO - 'XPC_FLAGS': '0x0', 18:02:02 INFO - 'XPC_SERVICE_NAME': '0', 18:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:02 INFO - Downloading/unpacking psutil>=3.1.1 18:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:02:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:04 INFO - Installing collected packages: psutil 18:02:04 INFO - Running setup.py install for psutil 18:02:04 INFO - building 'psutil._psutil_osx' extension 18:02:04 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 18:02:05 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 18:02:05 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 18:02:05 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 18:02:05 INFO - building 'psutil._psutil_posix' extension 18:02:05 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 18:02:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:02:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:02:05 INFO - ^ 18:02:05 INFO - 1 warning generated. 18:02:05 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 18:02:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:05 INFO - Successfully installed psutil 18:02:05 INFO - Cleaning up... 18:02:05 INFO - Return code: 0 18:02:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:05 INFO - 'HOME': '/Users/cltbld', 18:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:05 INFO - 'LOGNAME': 'cltbld', 18:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:05 INFO - 'MOZ_NO_REMOTE': '1', 18:02:05 INFO - 'NO_EM_RESTART': '1', 18:02:05 INFO - 'PAGER': '/bin/cat', 18:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:05 INFO - 'PWD': '/builds/slave/test', 18:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:05 INFO - 'SHELL': '/bin/bash', 18:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:05 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:05 INFO - 'USER': 'cltbld', 18:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:05 INFO - 'XPC_FLAGS': '0x0', 18:02:05 INFO - 'XPC_SERVICE_NAME': '0', 18:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:02:07 INFO - Installing collected packages: mozsystemmonitor 18:02:07 INFO - Running setup.py install for mozsystemmonitor 18:02:07 INFO - Successfully installed mozsystemmonitor 18:02:07 INFO - Cleaning up... 18:02:07 INFO - Return code: 0 18:02:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:08 INFO - 'HOME': '/Users/cltbld', 18:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:08 INFO - 'LOGNAME': 'cltbld', 18:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:08 INFO - 'MOZ_NO_REMOTE': '1', 18:02:08 INFO - 'NO_EM_RESTART': '1', 18:02:08 INFO - 'PAGER': '/bin/cat', 18:02:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:08 INFO - 'PWD': '/builds/slave/test', 18:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:08 INFO - 'SHELL': '/bin/bash', 18:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:08 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:08 INFO - 'USER': 'cltbld', 18:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:08 INFO - 'XPC_FLAGS': '0x0', 18:02:08 INFO - 'XPC_SERVICE_NAME': '0', 18:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:08 INFO - Downloading/unpacking jsonschema==2.5.1 18:02:08 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 18:02:08 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 18:02:08 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 18:02:08 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 18:02:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:02:09 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 18:02:09 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 18:02:09 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 18:02:09 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 18:02:10 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:10 INFO - warning: no files found matching '*.txt' 18:02:10 INFO - no previously-included directories found matching 'build' 18:02:10 INFO - no previously-included directories found matching 'dist' 18:02:10 INFO - no previously-included directories found matching '.git*' 18:02:10 INFO - Installing collected packages: jsonschema, functools32 18:02:10 INFO - Running setup.py install for functools32 18:02:10 INFO - warning: no files found matching '*.txt' 18:02:10 INFO - no previously-included directories found matching 'build' 18:02:10 INFO - no previously-included directories found matching 'dist' 18:02:10 INFO - no previously-included directories found matching '.git*' 18:02:10 INFO - Successfully installed jsonschema functools32 18:02:10 INFO - Cleaning up... 18:02:10 INFO - Return code: 0 18:02:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:10 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 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:10 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 18:02:10 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 18:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:10 INFO - 'HOME': '/Users/cltbld', 18:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:10 INFO - 'LOGNAME': 'cltbld', 18:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:10 INFO - 'MOZ_NO_REMOTE': '1', 18:02:10 INFO - 'NO_EM_RESTART': '1', 18:02:10 INFO - 'PAGER': '/bin/cat', 18:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:10 INFO - 'PWD': '/builds/slave/test', 18:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:10 INFO - 'SHELL': '/bin/bash', 18:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:10 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:10 INFO - 'USER': 'cltbld', 18:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:10 INFO - 'XPC_FLAGS': '0x0', 18:02:10 INFO - 'XPC_SERVICE_NAME': '0', 18:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:02:11 INFO - Cleaning up... 18:02:11 INFO - Return code: 0 18:02:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:11 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 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:11 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 18:02:11 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 18:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:11 INFO - 'HOME': '/Users/cltbld', 18:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:11 INFO - 'LOGNAME': 'cltbld', 18:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:11 INFO - 'MOZ_NO_REMOTE': '1', 18:02:11 INFO - 'NO_EM_RESTART': '1', 18:02:11 INFO - 'PAGER': '/bin/cat', 18:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:11 INFO - 'PWD': '/builds/slave/test', 18:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:11 INFO - 'SHELL': '/bin/bash', 18:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:11 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:11 INFO - 'USER': 'cltbld', 18:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:11 INFO - 'XPC_FLAGS': '0x0', 18:02:11 INFO - 'XPC_SERVICE_NAME': '0', 18:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:11 INFO - Downloading/unpacking blobuploader==1.2.4 18:02:11 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 18:02:11 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 18:02:11 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 18:02:11 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 18:02:12 INFO - Downloading blobuploader-1.2.4.tar.gz 18:02:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:02:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:02:12 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 18:02:12 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 18:02:12 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 18:02:12 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 18:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:13 INFO - Downloading docopt-0.6.1.tar.gz 18:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:13 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:13 INFO - Running setup.py install for blobuploader 18:02:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:14 INFO - Running setup.py install for requests 18:02:14 INFO - Running setup.py install for docopt 18:02:14 INFO - Successfully installed blobuploader requests docopt 18:02:14 INFO - Cleaning up... 18:02:14 INFO - Return code: 0 18:02:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:14 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')]} 18:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:14 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')]} 18:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:14 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 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:14 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 18:02:14 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 18:02:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:14 INFO - 'HOME': '/Users/cltbld', 18:02:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:14 INFO - 'LOGNAME': 'cltbld', 18:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:14 INFO - 'MOZ_NO_REMOTE': '1', 18:02:14 INFO - 'NO_EM_RESTART': '1', 18:02:14 INFO - 'PAGER': '/bin/cat', 18:02:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:14 INFO - 'PWD': '/builds/slave/test', 18:02:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:14 INFO - 'SHELL': '/bin/bash', 18:02:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:14 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:14 INFO - 'USER': 'cltbld', 18:02:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:14 INFO - 'XPC_FLAGS': '0x0', 18:02:14 INFO - 'XPC_SERVICE_NAME': '0', 18:02:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-4nGUTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ACb1vV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-5ZwhDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-t2Hc9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-4rR_zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DB3Ny6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-aqA75T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-iNcOlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-QhYEzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-awJIfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Jxatoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-q1R3nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-oWoKRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-BlPPF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DrIoaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Mc_C8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-86qa8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:16 INFO - Running setup.py install for manifestparser 18:02:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:16 INFO - Running setup.py install for mozcrash 18:02:17 INFO - Running setup.py install for mozdebug 18:02:17 INFO - Running setup.py install for mozdevice 18:02:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:17 INFO - Running setup.py install for mozfile 18:02:17 INFO - Running setup.py install for mozhttpd 18:02:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:17 INFO - Running setup.py install for mozinfo 18:02:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:17 INFO - Running setup.py install for mozInstall 18:02:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:17 INFO - Running setup.py install for mozleak 18:02:18 INFO - Running setup.py install for mozlog 18:02:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:18 INFO - Running setup.py install for moznetwork 18:02:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:18 INFO - Running setup.py install for mozprocess 18:02:18 INFO - Running setup.py install for mozprofile 18:02:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:18 INFO - Running setup.py install for mozrunner 18:02:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:18 INFO - Running setup.py install for mozscreenshot 18:02:19 INFO - Running setup.py install for moztest 18:02:19 INFO - Running setup.py install for mozversion 18:02:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:19 INFO - Cleaning up... 18:02:19 INFO - Return code: 0 18:02:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02: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')]} 18:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:19 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 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:19 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 18:02:19 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 18:02:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:19 INFO - 'HOME': '/Users/cltbld', 18:02:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:19 INFO - 'LOGNAME': 'cltbld', 18:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:19 INFO - 'MOZ_NO_REMOTE': '1', 18:02:19 INFO - 'NO_EM_RESTART': '1', 18:02:19 INFO - 'PAGER': '/bin/cat', 18:02:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:19 INFO - 'PWD': '/builds/slave/test', 18:02:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:19 INFO - 'SHELL': '/bin/bash', 18:02:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:19 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:19 INFO - 'USER': 'cltbld', 18:02:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:19 INFO - 'XPC_FLAGS': '0x0', 18:02:19 INFO - 'XPC_SERVICE_NAME': '0', 18:02:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-HS6vX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:19 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)) 18:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-XBvlcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:19 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)) 18:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-07mcdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:19 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)) 18:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-FbkdLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-OX_aL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:20 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)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-OP78gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:20 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)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yg80vB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hHJBPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-_4VPg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:20 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)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-atgoDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:20 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)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-KcAE18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:20 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)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-OtKGCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:20 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)) 18:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-11NlPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:21 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)) 18:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:21 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-zk_Xso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:21 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)) 18:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:21 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Rtpu5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:21 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)) 18:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:21 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-_73Z6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:21 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)) 18:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:21 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-jY3JVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:21 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)) 18:02:21 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)) 18:02:21 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)) 18:02:21 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)) 18:02:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:02:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:02:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02:21 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 18:02:21 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 18:02:21 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 18:02:21 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 18:02:22 INFO - Downloading blessings-1.6.tar.gz 18:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:22 INFO - Installing collected packages: blessings 18:02:22 INFO - Running setup.py install for blessings 18:02:23 INFO - Successfully installed blessings 18:02:23 INFO - Cleaning up... 18:02:23 INFO - Return code: 0 18:02:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:23 INFO - Reading from file tmpfile_stdout 18:02:23 INFO - Current package versions: 18:02:23 INFO - blessings == 1.6 18:02:23 INFO - blobuploader == 1.2.4 18:02:23 INFO - docopt == 0.6.1 18:02:23 INFO - functools32 == 3.2.3-2 18:02:23 INFO - jsonschema == 2.5.1 18:02:23 INFO - manifestparser == 1.1 18:02:23 INFO - mozInstall == 1.12 18:02:23 INFO - mozcrash == 1.0 18:02:23 INFO - mozdebug == 0.1 18:02:23 INFO - mozdevice == 0.50 18:02:23 INFO - mozfile == 1.2 18:02:23 INFO - mozhttpd == 0.7 18:02:23 INFO - mozinfo == 0.9 18:02:23 INFO - mozleak == 0.1 18:02:23 INFO - mozlog == 3.4 18:02:23 INFO - moznetwork == 0.27 18:02:23 INFO - mozprocess == 0.25 18:02:23 INFO - mozprofile == 0.28 18:02:23 INFO - mozrunner == 6.13 18:02:23 INFO - mozscreenshot == 0.1 18:02:23 INFO - mozsystemmonitor == 0.3 18:02:23 INFO - moztest == 0.8 18:02:23 INFO - mozversion == 1.4 18:02:23 INFO - psutil == 3.1.1 18:02:23 INFO - requests == 1.2.3 18:02:23 INFO - wsgiref == 0.1.2 18:02:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:23 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')]} 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:23 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')]} 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:23 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 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:23 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 18:02:23 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 18:02:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:23 INFO - 'HOME': '/Users/cltbld', 18:02:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:23 INFO - 'LOGNAME': 'cltbld', 18:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:23 INFO - 'MOZ_NO_REMOTE': '1', 18:02:23 INFO - 'NO_EM_RESTART': '1', 18:02:23 INFO - 'PAGER': '/bin/cat', 18:02:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:23 INFO - 'PWD': '/builds/slave/test', 18:02:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:23 INFO - 'SHELL': '/bin/bash', 18:02:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:23 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:23 INFO - 'USER': 'cltbld', 18:02:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:23 INFO - 'XPC_FLAGS': '0x0', 18:02:23 INFO - 'XPC_SERVICE_NAME': '0', 18:02:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 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)) 18:02:23 INFO - Cleaning up... 18:02:23 INFO - Return code: 0 18:02:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02:23 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')]} 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:23 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')]} 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:23 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 0x10640eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106411768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1a588d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, '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.FqyAsb5yki/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.NhT9SoXwUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 18:02:23 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 18:02:23 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 18:02:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:23 INFO - 'HOME': '/Users/cltbld', 18:02:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:23 INFO - 'LOGNAME': 'cltbld', 18:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:23 INFO - 'MOZ_NO_REMOTE': '1', 18:02:23 INFO - 'NO_EM_RESTART': '1', 18:02:23 INFO - 'PAGER': '/bin/cat', 18:02:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:23 INFO - 'PWD': '/builds/slave/test', 18:02:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:23 INFO - 'SHELL': '/bin/bash', 18:02:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:23 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:23 INFO - 'USER': 'cltbld', 18:02:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:23 INFO - 'XPC_FLAGS': '0x0', 18:02:23 INFO - 'XPC_SERVICE_NAME': '0', 18:02:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:02:24 INFO - Cleaning up... 18:02:24 INFO - Return code: 0 18:02:24 INFO - Running post-action listener: _resource_record_post_action 18:02:24 INFO - Running post-action listener: _start_resource_monitoring 18:02:24 INFO - Starting resource monitoring. 18:02:24 INFO - [mozharness: 2017-05-12 01:02:24.078314Z] Finished create-virtualenv step (success) 18:02:24 INFO - [mozharness: 2017-05-12 01:02:24.079299Z] Running install step. 18:02:24 INFO - Running pre-action listener: _resource_record_pre_action 18:02:24 INFO - Running main action method: install 18:02:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:24 INFO - Reading from file tmpfile_stdout 18:02:24 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:24 INFO - Reading from file tmpfile_stdout 18:02:24 INFO - Output received: 18:02:24 INFO - Usage: mozinstall [options] installer 18:02:24 INFO - Options: 18:02:24 INFO - -h, --help show this help message and exit 18:02:24 INFO - -d DEST, --destination=DEST 18:02:24 INFO - Directory to install application into. [default: 18:02:24 INFO - "/builds/slave/test"] 18:02:24 INFO - --app=APP Application being installed. [default: firefox] 18:02:24 INFO - mkdir: /builds/slave/test/build/application 18:02:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:02:44 INFO - Reading from file tmpfile_stdout 18:02:44 INFO - Output received: 18:02:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:02:44 INFO - Running post-action listener: _resource_record_post_action 18:02:44 INFO - [mozharness: 2017-05-12 01:02:44.947242Z] Finished install step (success) 18:02:44 INFO - [mozharness: 2017-05-12 01:02:44.947379Z] Running run-tests step. 18:02:44 INFO - Running pre-action listener: _resource_record_pre_action 18:02:44 INFO - Running main action method: run_tests 18:02:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:02:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:02:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:02:44 INFO - 2017-05-11 18:02:44.962 screenresolution[2758:38280] starting screenresolution argv=screenresolution get 18:02:44 INFO - 2017-05-11 18:02:44.965 screenresolution[2758:38280] Display 0: 1600x1200x32@60 18:02:44 INFO - 2017-05-11 18:02:44.970 screenresolution[2759:38284] starting screenresolution argv=screenresolution list 18:02:44 INFO - Available Modes on Display 0 18:02:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:02:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:02:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:02:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:02:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:02:45 INFO - Intel Iris: 18:02:45 INFO - Chipset Model: Intel Iris 18:02:45 INFO - Type: GPU 18:02:45 INFO - Bus: Built-In 18:02:45 INFO - VRAM (Dynamic, Max): 1536 MB 18:02:45 INFO - Vendor: Intel (0x8086) 18:02:45 INFO - Device ID: 0x0a2e 18:02:45 INFO - Revision ID: 0x0009 18:02:45 INFO - Displays: 18:02:45 INFO - Display: 18:02:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:02:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:02:45 INFO - Display Serial Number: 1600x1200 60 18:02:45 INFO - Main Display: Yes 18:02:45 INFO - Mirror: Off 18:02:45 INFO - Online: Yes 18:02:45 INFO - Rotation: Supported 18:02:45 INFO - Return code: 0 18:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:45 INFO - Python 2.7.3 18:02:45 INFO - Return code: 0 18:02:45 INFO - grabbing minidump binary from tooltool 18:02:45 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')]} 18:02:45 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 0x10611fd30>, '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 0x1060d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106113be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:45 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 18:02:45 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 18:02:45 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 18:02:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:45 INFO - Return code: 0 18:02:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:02:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:45 INFO - ENV: RUST_BACKTRACE is now 1 18:02:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:02:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 18:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 18:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FqyAsb5yki/Render', 18:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:45 INFO - 'HOME': '/Users/cltbld', 18:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:45 INFO - 'LOGNAME': 'cltbld', 18:02:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:45 INFO - 'MOZ_NO_REMOTE': '1', 18:02:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:45 INFO - 'NO_EM_RESTART': '1', 18:02:45 INFO - 'PAGER': '/bin/cat', 18:02:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:45 INFO - 'PWD': '/builds/slave/test', 18:02:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:45 INFO - 'RUST_BACKTRACE': '1', 18:02:45 INFO - 'SHELL': '/bin/bash', 18:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NhT9SoXwUV/Listeners', 18:02:45 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 18:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:45 INFO - 'USER': 'cltbld', 18:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:45 INFO - 'XPC_FLAGS': '0x0', 18:02:45 INFO - 'XPC_SERVICE_NAME': '0', 18:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 18:02:45 INFO - mozversion application_buildid: 20170511164726 18:02:45 INFO - mozversion application_changeset: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 18:02:45 INFO - mozversion application_display_name: Nightly 18:02:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:45 INFO - mozversion application_name: Firefox 18:02:45 INFO - mozversion application_remotingname: firefox 18:02:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:02:45 INFO - mozversion application_vendor: Mozilla 18:02:45 INFO - mozversion application_version: 55.0a1 18:02:45 INFO - mozversion platform_buildid: 20170511164726 18:02:45 INFO - mozversion platform_changeset: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 18:02:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:02:45 INFO - mozversion platform_version: 55.0a1 18:02:45 INFO - using testdate: 1494550965 18:02:45 INFO - actual date: 1494550965 18:02:45 INFO - starting webserver on 'localhost:58335' 18:02:45 INFO - SUITE-START | Running 2 tests 18:02:45 INFO - TEST-START | dromaeo_css 18:02:45 INFO - operating with platform_type : mac_ 18:02:45 INFO - Initialising browser for dromaeo_css test... 18:02:45 INFO - TEST-INFO | started process 2769 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpHyoxOY/profile http://localhost:58335/getInfo.html) 18:02:48 INFO - PID 2769 | 2017-05-11 18:02:48.066 plugin-container[2771:38454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f53, name = 'com.apple.tsm.portname' 18:02:48 INFO - PID 2769 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:48 INFO - PID 2769 | 2017-05-11 18:02:48.069 plugin-container[2771:38454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990f, name = 'com.apple.CFPasteboardClient' 18:02:48 INFO - PID 2769 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:48 INFO - PID 2769 | 2017-05-11 18:02:48.069 plugin-container[2771:38454] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:48 INFO - PID 2769 | __metrics Screen width/height:1600/1200 18:02:48 INFO - PID 2769 | colorDepth:24 18:02:48 INFO - PID 2769 | Browser inner width/height: 1024/691 18:02:48 INFO - PID 2769 | __metrics 18:02:49 INFO - TEST-INFO | 2769: exit 0 18:02:49 INFO - Browser initialized. 18:02:49 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:49 INFO - TEST-INFO | started process 2772 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpHyoxOY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:51 INFO - PID 2772 | 2017-05-11 18:02:51.237 plugin-container[2773:38576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 18:02:51 INFO - PID 2772 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:51 INFO - PID 2772 | 2017-05-11 18:02:51.240 plugin-container[2773:38576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 18:02:51 INFO - PID 2772 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:51 INFO - PID 2772 | 2017-05-11 18:02:51.240 plugin-container[2773:38576] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:51 INFO - PID 2772 | 2017-05-11 18:02:51.952 plugin-container[2774:38620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 18:02:51 INFO - PID 2772 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:51 INFO - PID 2772 | 2017-05-11 18:02:51.952 plugin-container[2774:38620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 18:02:51 INFO - PID 2772 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:51 INFO - PID 2772 | 2017-05-11 18:02:51.952 plugin-container[2774:38620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:03:01 INFO - PID 2772 | RSS: Main: 228663296 18:03:01 INFO - PID 2772 | 18:06:04 INFO - PID 2772 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58335/tests/dromaeo/cssquery-ext.html) 18:06:06 INFO - PID 2772 | RSS: Main: 251678720 18:06:06 INFO - PID 2772 | 18:09:08 INFO - PID 2772 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-ext.html (next: http://localhost:58335/tests/dromaeo/cssquery-jquery.html) 18:09:09 INFO - PID 2772 | RSS: Main: 255717376 18:09:09 INFO - PID 2772 | 18:12:10 INFO - PID 2772 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58335/tests/dromaeo/cssquery-mootools.html) 18:12:11 INFO - PID 2772 | RSS: Main: 266358784 18:12:11 INFO - PID 2772 | 18:15:13 INFO - PID 2772 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58335/tests/dromaeo/cssquery-prototype.html) 18:15:13 INFO - PID 2772 | RSS: Main: 259190784 18:15:13 INFO - PID 2772 | 18:18:16 INFO - PID 2772 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58335/tests/dromaeo/cssquery-yui.html) 18:18:17 INFO - PID 2772 | RSS: Main: 263426048 18:18:17 INFO - PID 2772 | 18:21:18 INFO - PID 2772 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-yui.html (next: http://localhost:58335/tests/dromaeo/cssquery-dojo.html) 18:21:19 INFO - PID 2772 | RSS: Main: 262361088 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | __start_tp_report 18:21:19 INFO - PID 2772 | _x_x_mozilla_page_load 18:21:19 INFO - PID 2772 | _x_x_mozilla_page_load_details 18:21:19 INFO - PID 2772 | |i|pagename|runs| 18:21:19 INFO - PID 2772 | |0;dojo.html;3028.515437529995;3573.356795776761;3597.1007248187952;3617.3669607818624;3621.1671315597414;4900.558949694527;4945;6218.564700470969;6305.495560355178;6346.048092786073;6221.066839974006;6229.532785041118;6272.278687950887;6306.7161977711;6333.493320534358;5057.848264552051;5307.230451584518;5428.4028756836715;5467.753951072192;5476.945230547683;5610.24261724666;5620.1288800235825;5636.774529018834;5642.266505354319;5670.4896559309655;5419.864503387407;5486.067368547357;5491.395946445888;5526.419725928759;5536.916946245829;4433.246348120827;5476.534494567966;5562.137868630329;5581.190727344553;5603.971980140073;5511.421300763443;5521.365043020064;5530.695811730353;5543.750531226065;5563.443655634444;6141.109539116847;6263.843403914893;6314.968425157891;6348.301686814447;6357.1735674362035;6505.512086593477;6602.40578347951;6672.666366681695;6727;6752.8311792205095;6770.119884415044;6847.726090956356;6850.828729281758;6850.897236541456;6875.106236189264;6320.652364119972;6352.1742173517605;6392.7123279452535;6418.19772528434;6426.485881129545;6658.101156343929;6773.288804675537;6891.896621550681;6985.1617805863625;6996.370326670522;6376.33048529907;6487.675616219264;6492.480601551864;6567.868642627121;6684.097646817618;5594.636348637326;6715.7985260442265;6751.054852320679;6787.287334829871;6798.796036118924;6749.93250067493;6761.594304341756;6783.694733736994;6788.93211067883;6792.660366981631;6495.610263384212;6567.704453299613;6752.831179220559;6789.830254243585;6818.113645226188;5361.463853614608;5372.221027950925;5416.052190866582;5421.457854214547;5451.291332126798;4776.665633405628;4776.852327792309;4780.49804770501;4787.808487660524;4836.758162091881;6319.55763096588;6365.586236894587;6366.968165159237;6379.64911929839;6397.936020639827;6624.701888415033;6752.527323087336;6779.457643388517;6819.215790184192;6834.692438840264;6323.52573556985;6388.776392826228;6404.455621272151;6407.551471397051;6408.711607977652;5962.314333851576;5965;5984.042553191469;5986.161937328778;5991.760329586855;5671.517920976763;6715.832104197434;6824.385805277549;6961;7060.435165186876;6151.538634602333;6796.388325050769;6842.281560436281;6878.8624227516175;6881.587104773729;6952;7004.544704594185;7015.859682806417;7021.017057611836;7021.964890175721;6410.358964103552;6528.118703974903;6748.089007983859;6795.966020169867;6817.5909445433435;5838.32859221178;5957.493613042767;6489.123968264413;6733.124693789776;6743.9430973553535;6444.516661250331;6640.501962352746;6642.634655094016;6646.667666616747;6665.0668906352175;6459.128017717548;6483.2544257411;6695.464362851058;6705.161854768154;6714.362135597111;5323.866903327448;6108.816735497942;6517.283098859027;6517.543771935919;6542.640154791532;5627.606067575231;6390.105385246162;6475.125858009108;6494.090827284279;6555.541112122106;5959.493443057216;6383.872322553616;6511.38141876521;6516.413522782972;6525.347465253437 18:21:19 INFO - PID 2772 | |1;ext.html;682.6927882452895;754.1440465072145;829.7925518620343;1224.1859163656163;1291.1671971578326;38934.22131557367;40442.19115617686;40486.7975660122;40489.19021619566;40667.796661016655;40914.386284205684;40991.59008409915;41372.58627413733;41408.17183656325;41698.583014169846;7930.286274235304;8102.837943241132;8423.789405264855;8545.931758530183;8592.226699597035;7189.8202544936275;8464.66141354345;8489;8589.742307730747;8617.009043959932;8582.055973842873;8635.568221588926;8638.870416943751;8656;8659.04750477447;8587.184217499327;8590.398672092955;8600.8709869352;8607.225349718556;8631.136886311382;8422.915770842274;8423.031351394608;8469.068402475723;8507.532085735344;8511.27654149398;37821.81089094564;37943.4308485373;38096;38172;38218.23563528742;3617.222297206113;3638.0904773806546;3650.908727281839;3653.0502069461863;3653.4885116083506;31026.379472410652;31142;31171.688283117106;31183.84408077945;31402.685973140204;3071.2014876132143;3118.937621247585;3128.5307203919365;3138.246820763023;3138.5919830422126;307050;308902;309700;316133.41933290416;318078;48122.75938620354;48651.513484865405;48652.27021594679;48765.512344876806;48875;50410;50456.49543504518;54518;55590.72204638951;55767;14244.572662820132;14290.642733931527;14357.353919073666;14418.062825916282;14443.78334324986;11403;11515.942420287845;11530.077593792477;11531.538738450368;11532.538698452136;14253.857461425254;14255.002149849619;14286.999910006407;14311.78532322016;14336.49822256216;5293.973530132324;5600.383957764642;6211.3478084801345;6232.127502149703;6285.685715714196;942.3639043645512;1015.4212099103588;1019.1235537437946;1047.2093569355088;1062.166199533378;25142.742862856783;25215.243542693748;25465.745342546707;25491.49017019649;25506.576875196828;1111.6553868300853;1114.793763153803;1149.6665966869682;1151.6372342712018;1164.4410682872099;10855.565777368816;12510.18683785551;12913.03152263584;13015.804762928563;13040.087193896336;312668;329951;330215;331330;331406;54656.90686186254;55084.44915550874;55128;55428.72285638546;55488.72255638696;1474.4913005013252;1528.3275358842075;1528.4497580870654;1534.915579643108;1554.8911576189557;24700.131941945485;30359.69640303657;30382.088537343916;30704.38591228118;31055.84472077625;54076.377708668806;54128;54363.18455223096;54376;54558;285543;285885;286060;286743;288025.5598721993;35944.24892017265;37219.81390093032;37275;37297.25405491929;37326.25347493089;44516.10967780691;45161.77419112883;45438.318425222984;47011.764941175075;47155;28029.719702803526;30204.24489387695;30401.23996900095;30951.69048309488;31950.201244969063;25625.23124306274;29121.689523971047;30664.693353067076;30720.231994200323;31680.683193167773;38369.42445863259;38435.6156438432;38459.61540384672;38508.80745596254;38809.6119038806;27851;29095.272618184714;29915.102546923627;32150.678493214768;32282.515762264055;22839.88580057089;23179.4205144866;24991;25055.624165637164;25360 18:21:19 INFO - PID 2772 | |2;jquery.html;431.70643962105754;436.50674737546575;439.73396095362307;458.7270574008466;459.6851156957484;19893.00534973251;21268.57462850744;21932.561348773015;22064.227752028663;22149.335519934426;22183;22862.77137228627;23447;23455.882720586374;23497.177598784045;5985.730642121115;6092.208012958309;6165.229346331708;6183.876322473548;6184.907226391608;5169.948300516984;5813.215215945836;6044.486218671418;6107.81676549702;6108.786192483264;5985.820425387224;6001.549883758714;6006.069059295816;6059.939400605982;6077.969610151943;5854.677992710399;6040.758369665252;6047.092936059582;6051.304100028509;6068.029115341568;4743.857684269477;5254.08053590624;5376.1398176292;5399.757010934517;5409.891802163935;20061.79938200614;26247.868760656267;26727.732722672914;27208.727912720817;27328.59007114895;2141.593097311506;2188.682641017044;2196.352076137527;2213.4245096275085;2215.3132528915926;17018.319267229297;17297.827021729747;17764.733528997076;17911;17957.64084718298;2153.138744502196;2164.945876353088;2201.0535469748;2201.900914458853;2206.7131272934416;162896;162954;166137;167257;167272;12378.381080945917;12449.688757780947;12703.809442858364;12741.808872866914;12776.6805829855;16388.75416868748;16643.50069497917;16645.918015328964;16653.41713040062;16743.497695068923;6675.365840245169;7361;7761.262680045386;9225.630974760934;9240.306976976754;7008.579485230903;8534.70128545498;8579.571021449028;8760.78098047554;8778.736637900873;6768.526203165829;6895.207051189077;8016.158303378178;8216.83566328682;8265.669373225004;1938.9045189468188;2070.3685375960476;2165.7076294700328;2178.7167668203033;2338.883055847201;936.6159874451442;953.3993584042148;978.4862946952907;989.6684610655511;991.4299277915228;20586.897065514575;21717.45706357322;21738.456538586663;22022.155125872283;25095;885.526243459746;921.3964853021148;997.7755005123789;1015.2030655935056;1033.705393962717;10696.625618103484;11357.716057098474;11360.886391135982;11436.771264574829;11651.708707282216;528552;529093;535192;546145;552922;129220;130245.69754302525;130876;133962;140049;919.7148883846029;1228.0421271408316;1237.1834589171103;1238.139493052338;1239.7334573066835;22842.42893927665;22861.31416057521;22930;23115.537689246456;23258.534829302982;42985;48784;53053;57878;59128.52178695567;79604.60197698974;79965;81159.59420202862;82166.58916705378;82275.17724822676;8885.378023538291;8927.553622318728;9177.678781242625;9253.58358873839;9411.670591529264;11989.561252649737;13352.599422017354;14169.291535423394;14569.9801013928;15053.924730376279;10957.780844383227;11441.656750297505;12061.819072714092;12091.334976576372;12310.507579696712;11503.53985840557;11514.712132196762;11543.711407214887;11575.710607234887;11916.404179791149;11960.34218117977;12273.69315767113;12333.506659733868;12399.776058225107;12459.314737689514;8407.789805254917;9049.095090490899;9056.275497960016;9194.908050919672;9303;11900.583479578174;12041.277523348626;12051.216670916363;12111;12148.574799882315 18:21:19 INFO - PID 2772 | |3;mootools.html;1385.6466601016743;1431.1198612853093;1444.4222311075569;1449.7535418978787;1467.2150399536247;11555.19113662044;11804.940975295121;11981.460834262458;12013.099017573684;12414.565490207844;11969.760604787922;12000;12171.14801963863;12181.025517958564;12253.081018923594;2225.1655629139073;2525.9621105683427;2540.64430979663;2574.034736973635;2585.4958283134797;2151.8278537716947;2504.1594428095336;2561;2562.436913482774;2563.0644814642706;2489.3776555861036;2509.7992160627196;2536.201096654557;2552.668153140089;2559.462512872299;2456.705195376557;2494.0148641286683;2507.18516482144;2517.5720127578174;2520.5841036229;2438.366024833538;2491.5639763041577;2538.1877799104295;2540.5173017126685;2564.871756412172;11723.648290551211;11864.644060678194;11971.880281197164;11985;12017.819732704016;5670.347909990322;6576.473882089469;6778.559393639398;6854.451643868479;6859.5541289816;3981.2634662587225;4302.161078589676;4366.6506679465565;4381.518033016366;4387.714798538085;1221.9572314969023;1225.8528976522875;1246.339440096741;1282.3780466473752;1289.2135797163724;15860.127692976881;16353.672926541403;16376.83623163765;16458.506244812674;16461.176941152895;5375.16684913839;5467.234587157801;5613.522850557667;5630.5495560355075;5671.9790437720785;11577.884221157849;11656.125790565742;11709.590164344378;11723.062155027577;11791.88208117925;6525.706343214567;6536.901946470807;6543.345665433514;6543.640099794462;6556.672166391661;5795.768169273182;6160.199174107337;6186.690665466709;6239.313675495692;6281.811545653638;4258.1057977825;4378.387025816388;4380.3210502372185;4412.889677758082;4421.46942366918;1244.1415423357855;1266.050462153385;1268.1313300389263;1288.4524077267124;1294.3010774181837;637.4708991537013;754.1402800807084;756.2513112019061;798.9920100799034;814.0638265994154;6072.605280656743;6113.144159817629;6223.751049958041;6319.58922670025;6418.903716444257;590.2945979554391;599.556328317051;602.881654530839;617.4967401567798;619.4765423217374;4039.3537034074407;4080.510338759368;4326.501315894367;4411.073674528385;4443.511213766483;10515.001074897873;14573.854261457462;14698.191599461918;15018.624534386508;15088.924555377373;13031.283279419533;13576.1854288743;13727.862721372658;14962.551123466314;14982.850171498363;6816.897746533806;8513.872291915626;8537.871931920901;8597.312215022783;8665.696700615574;5900.911486327623;6089.725962331794;6590.275069742421;6737.663116844236;6915.93084069173;13218;13390.732185356434;13518.39167237457;13613.931930340284;13684.178949263076;11473.36896470702;13849.930750346184;13967.441302347792;14010.509632162826;14027.438902443788;9272;9480.241580673668;9483.810323793623;9509.286803489627;9510.85733713981;10605.310654807412;10611.522481488197;10676.62631807883;10798.730031748955;10801.67594972152;7708.576028318272;8369.455985361175;9171.128742769426;9225.58484868196;9267.814643707223;7136.3220494052985;9004.41471304363;9059.22996545301;9068.863967040368;9113.453192808453;8267.421280510307;8291.709790157556;8404.621792019252;8410.789730256793;8430.030546487233;5301.098813201688;5885.323187833454;6020.157177994996;6080.361562035922;6226.937730622636;6084.421979911901;6232.906506402498;6285.685715714105;6303.527235457451;6325.177726895474 18:21:19 INFO - PID 2772 | |4;prototype.html;380.24331580155484;401.45803165726267;404.39139095661045;405.80318545505435;406.2200574896199;5039.748012599373;5311.787528498855;5736.967345877741;6043.516518678506;6229.221347331583;5247.950409918022;5580.386157522679;5788.382261086821;5997.820065398045;6156.5382596305235;3540.9291814163703;4297.871063868073;4766.642501812361;4768.23708206687;4813.90372192556;4025.81883815229;4639.907201855961;4739.289106633998;4749.382580264561;4771.618270538349;4363.716358436692;4433.711808732438;4717.952820471803;4729.219678753019;4748.453927798313;4738.739369334702;4743.952560474403;4753.524647535253;4756.643251756098;4761.833335833229;3438.82805859706;4132.628063474302;4215.494140703105;4228.556001619847;4233.957660423387;4921.950780492185;5944.989351810203;5959.649455897219;5975.850603734942;5987.760489580412;5625.71871406428;5643.347626615484;5649.745761440745;5654.688992105433;5685.800996965087;5995.970020149915;6240;6485.156929599169;6573.539852210347;6605.339466053356;4220.831166753326;4372.2130016597;4384.802683879201;4390.8902277443;4401.515833258339;6118.143459915571;6742.4268937140405;6929.688164032631;6993.930060699429;7000.7549735759;3447.190539710128;3806.5812760596314;4179.791010449465;4318.762468064224;4401.877521232065;3764.661180493769;3940.2119576084897;4446.644268458515;4490.753008584559;4497.8200871964755;4361.716488428306;5098.832995976769;5266.262723218752;5315.867103322448;5364.8122315718765;5421.783128674888;6352.110704501373;6422.9036564450635;6619.172603424572;6649.966750166218;1261.8990480761518;1485.685168625774;1517.8861585381142;1523.5505525869894;1531.257340190007;842.8356470488319;970.4371464550685;990.4993934189708;994.6121012805012;1008.3848852200152;5646.632968857011;6175.3515880832765;6176.413240742122;6189.690515474208;6219.751209951642;5516.9448305517235;5668.404817494104;6502.262307853639;6523.836904077341;6899.586024838526;5472.418133503804;6007.279126504847;6170.938290617037;6188.195534580475;6283.717232724538;3269.918252043718;3336.91550246171;3577;3610.4042832932796;3642.216923361437;5869.589128760946;6973.803251975612;7044.295570443017;7057.223705392403;7073.434125269966;7019.859602808017;7078.787636370917;7095;7114.217436082027;7125.750598729123;6139.877202456015;6513.348665133449;6848.452123830181;6982.581045137206;7058.329458701415;6166.520035191612;6193.916064688571;6364.236291645032;6530.836729081621;6846.212685541226;5068.72122033277;6696.052599177484;6702.899456508058;6715.764948226789;6721.865562688621;5835.533157347487;5985.6408615483215;6813.625250611264;7084.103742941465;7134.431604622228;3796.37359835624;4312.654987601047;4400.317950717644;4400.691951563488;4405.229084910064;6623.900641490478;6679.265280819203;6822.488313376417;6862.4853136016;6883.418717980757;5530.281063461885;5965.850853728692;6055.304514735881;6646;6706.966465167643;5355.812546560852;6682.365175308338;6692.631905245258;6693.698783554849;6714.462842972453;6393.776217832354;6507.381798729113;6556.967215163893;6577.704003319958;6583.901241481286;6678.332166783282;6701.195856497252;6705.1283333166375;6729.663516824041;6766.695498702472;6305.022721478178;6352.6506042166275;6462.224533056064;6521.087047813215;6544.312847150981 18:21:19 INFO - PID 2772 | |5;yui.html;765.8583162115008;835.3567752830317;851.9914800851993;870.6430363550943;874.4359887872323;13311.20132792032;16635.58411039723;16657.000579965195;18559.72160417595;19155.80844191558;14654.63363415915;15885.649719773804;17401.91299043503;18192.90903545484;18555.165017574207;990.7919336939233;1006.4011912911842;1010.7927874785652;1011.6054738651932;1015.6445244164557;1010.2019404670301;1011.8431643095332;1012.4583347908871;1020.4948550467492;1022.2946167144684;1058.2169194795833;1071.7213524483611;1073.9140868730522;1081.0702795595782;1081.2053140941402;1068.727474494003;1070.9892901071007;1073.2004656530855;1073.2809017957986;1073.7369344510566;738.7192866710669;788.8619491589006;794.047143427883;794.2811755361407;794.988075178867;17090.48728538146;17206.483805485856;17501.47495575135;17565.648687026187;17651.117444127744;16987.66024679512;17320.826791732048;17435.30258789647;17535.123243837756;17564.38524651644;14302.141871487744;14526.636834079232;14563;14570.417183312655;14632.048916820373;3625.583057948343;3634.2186429917697;3651.926961460756;3652.3790955537625;3657.5062366580437;126277.73722262752;126823;127781;129167;130193.99999999905;11837.408129593658;12447.688807779878;13261.66845828862;13432.462701492026;13507.324633768469;24977.62533561998;25452.618210726854;25845;26068.8696556516;26272.73727262741;7529.2470752925365;7623.618819059025;7667.8849817252785;7679.308862202429;7680.654370553339;3847.153626202231;3950.545687245946;3972.781497017634;3975.20495900821;3978.124812541236;4350.086481838786;4388.605025547652;4427.933580996288;4439.711418757771;4442.644588432918;875.1511034296722;892.2460520859863;893.8748575199477;895.0780695883251;896.7040876510736;644.3427703742251;654.3227759269133;655.3282885042773;671.9832004199934;672.7847088931495;11240.606578769868;11657.358845263592;11798.646040618794;11954.282743035272;12233.510659573518;549.3325609384617;612.8192183305936;663.4791688524485;683.729926678959;695.391532409142;5038.445770965191;5456.399796022434;5532.723363831792;5635.520980716603;5654.604177707521;101452;101613;102757.97242027633;103466.96533034724;104175.47912260391;97380.51309743548;98262;98724.50637746765;98925;100218;77869.61065194638;86102.13897861066;86874.82812929727;87420;87850.56074719585;8576.013758417612;10050.547725352522;10268.127209187303;10504.686914135733;10655.20085993569;37633;38633.840984770504;39069.60930390773;39505.604943951344;39938.400923986745;56506;72025;72042.91935621075;72136.63931680308;75210.6239468821;16522.83477165213;16806.66386672284;16887.66224675475;16940.915295423936;16979;17197.484075477754;18103.90948045304;18165;18432.07839608041;18449.81550184481;16414.09722465276;16477.91761041187;17072.146392680566;17134.485965421056;17157.57106072358;15290.694186115992;15352.23238838076;16354.018758874505;16678.916605416896;16998.57503562421;15976.760348595013;16508.174591270148;16560.254788534785;16625.168741562633;16741.414050508174;11886.702832429259;11900;12136.271823690608;12250.448729807002;12340.625218407373;12858.357082146042;12910.548130815376;12964;13037.282949437871;13093.214407135602 18:21:19 INFO - PID 2772 | __end_tp_report 18:21:19 INFO - PID 2772 | __start_cc_report 18:21:19 INFO - PID 2772 | _x_x_mozilla_cycle_collect,410 18:21:19 INFO - PID 2772 | __end_cc_report 18:21:19 INFO - PID 2772 | __startTimestamp1494552079078__endTimestamp 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | ------- Summary: start ------- 18:21:19 INFO - PID 2772 | Number of tests: 6 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | [#0] dojo.html Cycles:165 Average:6168.04 Median:6404.46 stddev:758.03 (11.8%) stddev-sans-first:719.23 18:21:19 INFO - PID 2772 | Values: 3028.5 3573.4 3597.1 3617.4 3621.2 4900.6 4945.0 6218.6 6305.5 6346.0 6221.1 6229.5 6272.3 6306.7 6333.5 5057.8 5307.2 5428.4 5467.8 5476.9 5610.2 5620.1 5636.8 5642.3 5670.5 5419.9 5486.1 5491.4 5526.4 5536.9 4433.2 5476.5 5562.1 5581.2 5604.0 5511.4 5521.4 5530.7 5543.8 5563.4 6141.1 6263.8 6315.0 6348.3 6357.2 6505.5 6602.4 6672.7 6727.0 6752.8 6770.1 6847.7 6850.8 6850.9 6875.1 6320.7 6352.2 6392.7 6418.2 6426.5 6658.1 6773.3 6891.9 6985.2 6996.4 6376.3 6487.7 6492.5 6567.9 6684.1 5594.6 6715.8 6751.1 6787.3 6798.8 6749.9 6761.6 6783.7 6788.9 6792.7 6495.6 6567.7 6752.8 6789.8 6818.1 5361.5 5372.2 5416.1 5421.5 5451.3 4776.7 4776.9 4780.5 4787.8 4836.8 6319.6 6365.6 6367.0 6379.6 6397.9 6624.7 6752.5 6779.5 6819.2 6834.7 6323.5 6388.8 6404.5 6407.6 6408.7 5962.3 5965.0 5984.0 5986.2 5991.8 5671.5 6715.8 6824.4 6961.0 7060.4 6151.5 6796.4 6842.3 6878.9 6881.6 6952.0 7004.5 7015.9 7021.0 7022.0 6410.4 6528.1 6748.1 6796.0 6817.6 5838.3 5957.5 6489.1 6733.1 6743.9 6444.5 6640.5 6642.6 6646.7 6665.1 6459.1 6483.3 6695.5 6705.2 6714.4 5323.9 6108.8 6517.3 6517.5 6542.6 5627.6 6390.1 6475.1 6494.1 6555.5 5959.5 6383.9 6511.4 6516.4 6525.3 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | [#1] ext.html Cycles:180 Average:46958.45 Median:183675.12 stddev:81006.75 (44.1%) stddev-sans-first:81159.48 18:21:19 INFO - PID 2772 | Values: 682.7 754.1 829.8 1224.2 1291.2 38934.2 40442.2 40486.8 40489.2 40667.8 40914.4 40991.6 41372.6 41408.2 41698.6 7930.3 8102.8 8423.8 8545.9 8592.2 7189.8 8464.7 8489.0 8589.7 8617.0 8582.1 8635.6 8638.9 8656.0 8659.0 8587.2 8590.4 8600.9 8607.2 8631.1 8422.9 8423.0 8469.1 8507.5 8511.3 37821.8 37943.4 38096.0 38172.0 38218.2 3617.2 3638.1 3650.9 3653.1 3653.5 31026.4 31142.0 31171.7 31183.8 31402.7 3071.2 3118.9 3128.5 3138.2 3138.6 307050.0 308902.0 309700.0 316133.4 318078.0 48122.8 48651.5 48652.3 48765.5 48875.0 50410.0 50456.5 54518.0 55590.7 55767.0 14244.6 14290.6 14357.4 14418.1 14443.8 11403.0 11515.9 11530.1 11531.5 11532.5 14253.9 14255.0 14287.0 14311.8 14336.5 5294.0 5600.4 6211.3 6232.1 6285.7 942.4 1015.4 1019.1 1047.2 1062.2 25142.7 25215.2 25465.7 25491.5 25506.6 1111.7 1114.8 1149.7 1151.6 1164.4 10855.6 12510.2 12913.0 13015.8 13040.1 312668.0 329951.0 330215.0 331330.0 331406.0 54656.9 55084.4 55128.0 55428.7 55488.7 1474.5 1528.3 1528.4 1534.9 1554.9 24700.1 30359.7 30382.1 30704.4 31055.8 54076.4 54128.0 54363.2 54376.0 54558.0 285543.0 285885.0 286060.0 286743.0 288025.6 35944.2 37219.8 37275.0 37297.3 37326.3 44516.1 45161.8 45438.3 47011.8 47155.0 28029.7 30204.2 30401.2 30951.7 31950.2 25625.2 29121.7 30664.7 30720.2 31680.7 38369.4 38435.6 38459.6 38508.8 38809.6 27851.0 29095.3 29915.1 32150.7 32282.5 22839.9 23179.4 24991.0 25055.6 25360.0 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | [#2] jquery.html Cycles:180 Average:35732.97 Median:23187.04 stddev:92026.67 (396.9%) stddev-sans-first:92246.66 18:21:19 INFO - PID 2772 | Values: 431.7 436.5 439.7 458.7 459.7 19893.0 21268.6 21932.6 22064.2 22149.3 22183.0 22862.8 23447.0 23455.9 23497.2 5985.7 6092.2 6165.2 6183.9 6184.9 5169.9 5813.2 6044.5 6107.8 6108.8 5985.8 6001.5 6006.1 6059.9 6078.0 5854.7 6040.8 6047.1 6051.3 6068.0 4743.9 5254.1 5376.1 5399.8 5409.9 20061.8 26247.9 26727.7 27208.7 27328.6 2141.6 2188.7 2196.4 2213.4 2215.3 17018.3 17297.8 17764.7 17911.0 17957.6 2153.1 2164.9 2201.1 2201.9 2206.7 162896.0 162954.0 166137.0 167257.0 167272.0 12378.4 12449.7 12703.8 12741.8 12776.7 16388.8 16643.5 16645.9 16653.4 16743.5 6675.4 7361.0 7761.3 9225.6 9240.3 7008.6 8534.7 8579.6 8760.8 8778.7 6768.5 6895.2 8016.2 8216.8 8265.7 1938.9 2070.4 2165.7 2178.7 2338.9 936.6 953.4 978.5 989.7 991.4 20586.9 21717.5 21738.5 22022.2 25095.0 885.5 921.4 997.8 1015.2 1033.7 10696.6 11357.7 11360.9 11436.8 11651.7 528552.0 529093.0 535192.0 546145.0 552922.0 129220.0 130245.7 130876.0 133962.0 140049.0 919.7 1228.0 1237.2 1238.1 1239.7 22842.4 22861.3 22930.0 23115.5 23258.5 42985.0 48784.0 53053.0 57878.0 59128.5 79604.6 79965.0 81159.6 82166.6 82275.2 8885.4 8927.6 9177.7 9253.6 9411.7 11989.6 13352.6 14169.3 14570.0 15053.9 10957.8 11441.7 12061.8 12091.3 12310.5 11503.5 11514.7 11543.7 11575.7 11916.4 11960.3 12273.7 12333.5 12399.8 12459.3 8407.8 9049.1 9056.3 9194.9 9303.0 11900.6 12041.3 12051.2 12111.0 12148.6 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | [#3] mootools.html Cycles:180 Average:7040.50 Median:4010.31 stddev:4444.66 (110.8%) stddev-sans-first:4436.82 18:21:19 INFO - PID 2772 | Values: 1385.6 1431.1 1444.4 1449.8 1467.2 11555.2 11804.9 11981.5 12013.1 12414.6 11969.8 12000.0 12171.1 12181.0 12253.1 2225.2 2526.0 2540.6 2574.0 2585.5 2151.8 2504.2 2561.0 2562.4 2563.1 2489.4 2509.8 2536.2 2552.7 2559.5 2456.7 2494.0 2507.2 2517.6 2520.6 2438.4 2491.6 2538.2 2540.5 2564.9 11723.6 11864.6 11971.9 11985.0 12017.8 5670.3 6576.5 6778.6 6854.5 6859.6 3981.3 4302.2 4366.7 4381.5 4387.7 1222.0 1225.9 1246.3 1282.4 1289.2 15860.1 16353.7 16376.8 16458.5 16461.2 5375.2 5467.2 5613.5 5630.5 5672.0 11577.9 11656.1 11709.6 11723.1 11791.9 6525.7 6536.9 6543.3 6543.6 6556.7 5795.8 6160.2 6186.7 6239.3 6281.8 4258.1 4378.4 4380.3 4412.9 4421.5 1244.1 1266.1 1268.1 1288.5 1294.3 637.5 754.1 756.3 799.0 814.1 6072.6 6113.1 6223.8 6319.6 6418.9 590.3 599.6 602.9 617.5 619.5 4039.4 4080.5 4326.5 4411.1 4443.5 10515.0 14573.9 14698.2 15018.6 15088.9 13031.3 13576.2 13727.9 14962.6 14982.9 6816.9 8513.9 8537.9 8597.3 8665.7 5900.9 6089.7 6590.3 6737.7 6915.9 13218.0 13390.7 13518.4 13613.9 13684.2 11473.4 13849.9 13967.4 14010.5 14027.4 9272.0 9480.2 9483.8 9509.3 9510.9 10605.3 10611.5 10676.6 10798.7 10801.7 7708.6 8369.5 9171.1 9225.6 9267.8 7136.3 9004.4 9059.2 9068.9 9113.5 8267.4 8291.7 8404.6 8410.8 8430.0 5301.1 5885.3 6020.2 6080.4 6226.9 6084.4 6232.9 6285.7 6303.5 6325.2 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | [#4] prototype.html Cycles:180 Average:5286.61 Median:5970.85 stddev:1675.10 (28.1%) stddev-sans-first:1638.82 18:21:19 INFO - PID 2772 | Values: 380.2 401.5 404.4 405.8 406.2 5039.7 5311.8 5737.0 6043.5 6229.2 5248.0 5580.4 5788.4 5997.8 6156.5 3540.9 4297.9 4766.6 4768.2 4813.9 4025.8 4639.9 4739.3 4749.4 4771.6 4363.7 4433.7 4718.0 4729.2 4748.5 4738.7 4744.0 4753.5 4756.6 4761.8 3438.8 4132.6 4215.5 4228.6 4234.0 4922.0 5945.0 5959.6 5975.9 5987.8 5625.7 5643.3 5649.7 5654.7 5685.8 5996.0 6240.0 6485.2 6573.5 6605.3 4220.8 4372.2 4384.8 4390.9 4401.5 6118.1 6742.4 6929.7 6993.9 7000.8 3447.2 3806.6 4179.8 4318.8 4401.9 3764.7 3940.2 4446.6 4490.8 4497.8 4361.7 5098.8 5266.3 5315.9 5364.8 5421.8 6352.1 6422.9 6619.2 6650.0 1261.9 1485.7 1517.9 1523.6 1531.3 842.8 970.4 990.5 994.6 1008.4 5646.6 6175.4 6176.4 6189.7 6219.8 5516.9 5668.4 6502.3 6523.8 6899.6 5472.4 6007.3 6170.9 6188.2 6283.7 3269.9 3336.9 3577.0 3610.4 3642.2 5869.6 6973.8 7044.3 7057.2 7073.4 7019.9 7078.8 7095.0 7114.2 7125.8 6139.9 6513.3 6848.5 6982.6 7058.3 6166.5 6193.9 6364.2 6530.8 6846.2 5068.7 6696.1 6702.9 6715.8 6721.9 5835.5 5985.6 6813.6 7084.1 7134.4 3796.4 4312.7 4400.3 4400.7 4405.2 6623.9 6679.3 6822.5 6862.5 6883.4 5530.3 5965.9 6055.3 6646.0 6707.0 5355.8 6682.4 6692.6 6693.7 6714.5 6393.8 6507.4 6557.0 6577.7 6583.9 6678.3 6701.2 6705.1 6729.7 6766.7 6305.0 6352.7 6462.2 6521.1 6544.3 18:21:19 INFO - PID 2772 | 18:21:19 INFO - PID 2772 | [#5] yui.html Cycles:180 Average:22478.58 Median:17468.39 stddev:31941.77 (182.9%) stddev-sans-first:31989.77 18:21:19 INFO - PID 2772 | Values: 765.9 835.4 852.0 870.6 874.4 13311.2 16635.6 16657.0 18559.7 19155.8 14654.6 15885.6 17401.9 18192.9 18555.2 990.8 1006.4 1010.8 1011.6 1015.6 1010.2 1011.8 1012.5 1020.5 1022.3 1058.2 1071.7 1073.9 1081.1 1081.2 1068.7 1071.0 1073.2 1073.3 1073.7 738.7 788.9 794.0 794.3 795.0 17090.5 17206.5 17501.5 17565.6 17651.1 16987.7 17320.8 17435.3 17535.1 17564.4 14302.1 14526.6 14563.0 14570.4 14632.0 3625.6 3634.2 3651.9 3652.4 3657.5 126277.7 126823.0 127781.0 129167.0 130194.0 11837.4 12447.7 13261.7 13432.5 13507.3 24977.6 25452.6 25845.0 26068.9 26272.7 7529.2 7623.6 7667.9 7679.3 7680.7 3847.2 3950.5 3972.8 3975.2 3978.1 4350.1 4388.6 4427.9 4439.7 4442.6 875.2 892.2 893.9 895.1 896.7 644.3 654.3 655.3 672.0 672.8 11240.6 11657.4 11798.6 11954.3 12233.5 549.3 612.8 663.5 683.7 695.4 5038.4 5456.4 5532.7 5635.5 5654.6 101452.0 101613.0 102758.0 103467.0 104175.5 97380.5 98262.0 98724.5 98925.0 100218.0 77869.6 86102.1 86874.8 87420.0 87850.6 8576.0 10050.5 10268.1 10504.7 10655.2 37633.0 38633.8 39069.6 39505.6 39938.4 56506.0 72025.0 72042.9 72136.6 75210.6 16522.8 16806.7 16887.7 16940.9 16979.0 17197.5 18103.9 18165.0 18432.1 18449.8 16414.1 16477.9 17072.1 17134.5 17157.6 15290.7 15352.2 16354.0 16678.9 16998.6 15976.8 16508.2 16560.3 16625.2 16741.4 11886.7 11900.0 12136.3 12250.4 12340.6 12858.4 12910.5 12964.0 13037.3 13093.2 18:21:19 INFO - PID 2772 | -------- Summary: end -------- 18:21:19 INFO - PID 2772 | 18:21:19 INFO - TEST-INFO | 2772: exit 0 18:21:19 INFO - TEST-OK | dromaeo_css | took 1114254ms 18:21:19 INFO - TEST-START | kraken 18:21:19 INFO - operating with platform_type : mac_ 18:21:19 INFO - Initialising browser for kraken test... 18:21:19 INFO - TEST-INFO | started process 2778 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp1luFr5/profile http://localhost:58335/getInfo.html) 18:21:22 INFO - PID 2778 | 2017-05-11 18:21:22.191 plugin-container[2779:40705] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 18:21:22 INFO - PID 2778 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:22 INFO - PID 2778 | 2017-05-11 18:21:22.224 plugin-container[2779:40705] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 18:21:22 INFO - PID 2778 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:22 INFO - PID 2778 | 2017-05-11 18:21:22.224 plugin-container[2779:40705] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:22 INFO - PID 2778 | __metrics Screen width/height:1600/1200 18:21:22 INFO - PID 2778 | colorDepth:24 18:21:22 INFO - PID 2778 | Browser inner width/height: 1024/691 18:21:22 INFO - PID 2778 | __metrics 18:21:23 INFO - TEST-INFO | 2778: exit 0 18:21:23 INFO - Browser initialized. 18:21:23 INFO - Running cycle 1/1 for kraken test... 18:21:23 INFO - TEST-INFO | started process 2780 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp1luFr5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:25 INFO - PID 2780 | 2017-05-11 18:21:25.469 plugin-container[2781:40825] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 18:21:25 INFO - PID 2780 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:25 INFO - PID 2780 | 2017-05-11 18:21:25.470 plugin-container[2781:40825] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 18:21:25 INFO - PID 2780 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:25 INFO - PID 2780 | 2017-05-11 18:21:25.470 plugin-container[2781:40825] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:26 INFO - PID 2780 | 2017-05-11 18:21:26.022 plugin-container[2782:40871] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 18:21:26 INFO - PID 2780 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:26 INFO - PID 2780 | 2017-05-11 18:21:26.023 plugin-container[2782:40871] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 18:21:26 INFO - PID 2780 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:26 INFO - PID 2780 | 2017-05-11 18:21:26.023 plugin-container[2782:40871] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:35 INFO - PID 2780 | RSS: Main: 228798464 18:21:35 INFO - PID 2780 | 18:21:37 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:38 INFO - PID 2780 | RSS: Main: 242311168 18:21:38 INFO - PID 2780 | 18:21:41 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-dft) 18:21:41 INFO - PID 2780 | RSS: Main: 250454016 18:21:41 INFO - PID 2780 | 18:21:45 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-fft) 18:21:45 INFO - PID 2780 | RSS: Main: 252305408 18:21:45 INFO - PID 2780 | 18:21:48 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-oscillator) 18:21:48 INFO - PID 2780 | RSS: Main: 253992960 18:21:48 INFO - PID 2780 | 18:21:50 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58335/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:51 INFO - PID 2780 | RSS: Main: 254218240 18:21:51 INFO - PID 2780 | 18:21:54 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58335/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:54 INFO - PID 2780 | RSS: Main: 260104192 18:21:54 INFO - PID 2780 | 18:21:58 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58335/tests/kraken/driver.html?testName=imaging-desaturate) 18:21:58 INFO - PID 2780 | RSS: Main: 263532544 18:21:58 INFO - PID 2780 | 18:22:01 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58335/tests/kraken/driver.html?testName=json-parse-financial) 18:22:02 INFO - PID 2780 | RSS: Main: 262619136 18:22:02 INFO - PID 2780 | 18:22:04 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58335/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:04 INFO - PID 2780 | RSS: Main: 261844992 18:22:04 INFO - PID 2780 | 18:22:06 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:07 INFO - PID 2780 | RSS: Main: 262008832 18:22:07 INFO - PID 2780 | 18:22:09 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:09 INFO - PID 2780 | RSS: Main: 261521408 18:22:09 INFO - PID 2780 | 18:22:12 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:13 INFO - PID 2780 | RSS: Main: 262037504 18:22:13 INFO - PID 2780 | 18:22:16 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:22:17 INFO - PID 2780 | RSS: Main: 261967872 18:22:17 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58335/tests/kraken/driver.html?testName=ai-astar) 18:22:19 INFO - PID 2780 | RSS: Main: 264114176 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | __start_tp_report 18:22:19 INFO - PID 2780 | _x_x_mozilla_page_load 18:22:19 INFO - PID 2780 | _x_x_mozilla_page_load_details 18:22:19 INFO - PID 2780 | |i|pagename|runs| 18:22:19 INFO - PID 2780 | |0;ai-astar;101;114;116;114;114;115;114;114;114;115 18:22:19 INFO - PID 2780 | |1;audio-beat-detection;134;110;110;151;110;108;112;111;136;109 18:22:19 INFO - PID 2780 | |2;audio-dft;199;202;198;204;203;251;243;199;202;195 18:22:19 INFO - PID 2780 | |3;audio-fft;83;60;69;112;84;76;75;87;74;80 18:22:19 INFO - PID 2780 | |4;audio-oscillator;76;97;67;75;54;73;84;64;79;112 18:22:19 INFO - PID 2780 | |5;imaging-gaussian-blur;86;89;77;136;119;88;96;89;85;134 18:22:19 INFO - PID 2780 | |6;imaging-darkroom;107;153;96;97;96;97;97;135;145;96 18:22:19 INFO - PID 2780 | |7;imaging-desaturate;91;94;90;91;136;87;138;147;89;90 18:22:19 INFO - PID 2780 | |8;json-parse-financial;50;50;51;54;44;44;71;81;77;68 18:22:19 INFO - PID 2780 | |9;json-stringify-tinderbox;56;65;56;61;60;59;57;56;56;55 18:22:19 INFO - PID 2780 | |10;stanford-crypto-aes;81;83;85;95;110;105;110;82;81;84 18:22:19 INFO - PID 2780 | |11;stanford-crypto-ccm;180;164;130;122;166;160;158;160;205;137 18:22:19 INFO - PID 2780 | |12;stanford-crypto-pbkdf2;238;237;235;182;178;181;189;187;177;186 18:22:19 INFO - PID 2780 | |13;stanford-crypto-sha256-iterative;70;68;82;99;98;93;69;66;66;71 18:22:19 INFO - PID 2780 | __end_tp_report 18:22:19 INFO - PID 2780 | __start_cc_report 18:22:19 INFO - PID 2780 | _x_x_mozilla_cycle_collect,1007 18:22:19 INFO - PID 2780 | __end_cc_report 18:22:19 INFO - PID 2780 | __startTimestamp1494552139482__endTimestamp 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | ------- Summary: start ------- 18:22:19 INFO - PID 2780 | Number of tests: 14 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:4.31 (3.8%) stddev-sans-first:0.73 18:22:19 INFO - PID 2780 | Values: 101.0 114.0 116.0 114.0 114.0 115.0 114.0 114.0 114.0 115.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:111.50 stddev:15.33 (13.7%) stddev-sans-first:15.28 18:22:19 INFO - PID 2780 | Values: 134.0 110.0 110.0 151.0 110.0 108.0 112.0 111.0 136.0 109.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#2] audio-dft Cycles:10 Average:209.60 Median:202.50 stddev:19.98 (9.9%) stddev-sans-first:20.82 18:22:19 INFO - PID 2780 | Values: 199.0 202.0 198.0 204.0 203.0 251.0 243.0 199.0 202.0 195.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#3] audio-fft Cycles:10 Average:80.00 Median:78.00 stddev:13.73 (17.6%) stddev-sans-first:14.52 18:22:19 INFO - PID 2780 | Values: 83.0 60.0 69.0 112.0 84.0 76.0 75.0 87.0 74.0 80.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:75.50 stddev:16.62 (22.0%) stddev-sans-first:17.61 18:22:19 INFO - PID 2780 | Values: 76.0 97.0 67.0 75.0 54.0 73.0 84.0 64.0 79.0 112.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#5] imaging-gaussian-blur Cycles:10 Average:99.90 Median:87.00 stddev:21.51 (24.7%) stddev-sans-first:22.22 18:22:19 INFO - PID 2780 | Values: 86.0 89.0 77.0 136.0 119.0 88.0 96.0 89.0 85.0 134.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:96.00 stddev:23.01 (24.0%) stddev-sans-first:24.34 18:22:19 INFO - PID 2780 | Values: 107.0 153.0 96.0 97.0 96.0 97.0 97.0 135.0 145.0 96.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#7] imaging-desaturate Cycles:10 Average:105.30 Median:90.00 stddev:24.40 (27.1%) stddev-sans-first:25.32 18:22:19 INFO - PID 2780 | Values: 91.0 94.0 90.0 91.0 136.0 87.0 138.0 147.0 89.0 90.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:61.00 stddev:13.88 (22.8%) stddev-sans-first:14.34 18:22:19 INFO - PID 2780 | Values: 50.0 50.0 51.0 54.0 44.0 44.0 71.0 81.0 77.0 68.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:58.00 stddev:3.14 (5.4%) stddev-sans-first:3.24 18:22:19 INFO - PID 2780 | Values: 56.0 65.0 56.0 61.0 60.0 59.0 57.0 56.0 56.0 55.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:82.50 stddev:12.29 (14.9%) stddev-sans-first:12.43 18:22:19 INFO - PID 2780 | Values: 81.0 83.0 85.0 95.0 110.0 105.0 110.0 82.0 81.0 84.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#11] stanford-crypto-ccm Cycles:10 Average:158.20 Median:162.00 stddev:24.32 (15.0%) stddev-sans-first:24.48 18:22:19 INFO - PID 2780 | Values: 180.0 164.0 130.0 122.0 166.0 160.0 158.0 160.0 205.0 137.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.00 Median:188.00 stddev:26.27 (14.0%) stddev-sans-first:23.77 18:22:19 INFO - PID 2780 | Values: 238.0 237.0 235.0 182.0 178.0 181.0 189.0 187.0 177.0 186.0 18:22:19 INFO - PID 2780 | 18:22:19 INFO - PID 2780 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.20 Median:76.50 stddev:13.60 (17.8%) stddev-sans-first:14.09 18:22:19 INFO - PID 2780 | Values: 70.0 68.0 82.0 99.0 98.0 93.0 69.0 66.0 66.0 71.0 18:22:19 INFO - PID 2780 | -------- Summary: end -------- 18:22:19 INFO - PID 2780 | 18:22:20 INFO - TEST-INFO | 2780: exit 0 18:22:20 INFO - TEST-OK | kraken | took 60779ms 18:22:20 INFO - SUITE-END | took 1175s 18:22:20 INFO - Completed test suite (00:19:35) 18:22:20 INFO - javascript benchmark 18:22:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7925.992722816042, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3028.515437529995, 3573.356795776761, 3597.1007248187952, 3617.3669607818624, 3621.1671315597414, 4900.558949694527, 4945.0, 6218.564700470969, 6305.495560355178, 6346.048092786073, 6221.066839974006, 6229.532785041118, 6272.278687950887, 6306.7161977711, 6333.493320534358, 5057.848264552051, 5307.230451584518, 5428.4028756836715, 5467.753951072192, 5476.945230547683, 5610.24261724666, 5620.1288800235825, 5636.774529018834, 5642.266505354319, 5670.4896559309655, 5419.864503387407, 5486.067368547357, 5491.395946445888, 5526.419725928759, 5536.916946245829, 4433.246348120827, 5476.534494567966, 5562.137868630329, 5581.190727344553, 5603.971980140073, 5511.421300763443, 5521.365043020064, 5530.695811730353, 5543.750531226065, 5563.443655634444, 6141.109539116847, 6263.843403914893, 6314.968425157891, 6348.301686814447, 6357.1735674362035, 6505.512086593477, 6602.40578347951, 6672.666366681695, 6727.0, 6752.8311792205095, 6770.119884415044, 6847.726090956356, 6850.828729281758, 6850.897236541456, 6875.106236189264, 6320.652364119972, 6352.1742173517605, 6392.7123279452535, 6418.19772528434, 6426.485881129545, 6658.101156343929, 6773.288804675537, 6891.896621550681, 6985.1617805863625, 6996.370326670522, 6376.33048529907, 6487.675616219264, 6492.480601551864, 6567.868642627121, 6684.097646817618, 5594.636348637326, 6715.7985260442265, 6751.054852320679, 6787.287334829871, 6798.796036118924, 6749.93250067493, 6761.594304341756, 6783.694733736994, 6788.93211067883, 6792.660366981631, 6495.610263384212, 6567.704453299613, 6752.831179220559, 6789.830254243585, 6818.113645226188, 5361.463853614608, 5372.221027950925, 5416.052190866582, 5421.457854214547, 5451.291332126798, 4776.665633405628, 4776.852327792309, 4780.49804770501, 4787.808487660524, 4836.758162091881, 6319.55763096588, 6365.586236894587, 6366.968165159237, 6379.64911929839, 6397.936020639827, 6624.701888415033, 6752.527323087336, 6779.457643388517, 6819.215790184192, 6834.692438840264, 6323.52573556985, 6388.776392826228, 6404.455621272151, 6407.551471397051, 6408.711607977652, 5962.314333851576, 5965.0, 5984.042553191469, 5986.161937328778, 5991.760329586855, 5671.517920976763, 6715.832104197434, 6824.385805277549, 6961.0, 7060.435165186876, 6151.538634602333, 6796.388325050769, 6842.281560436281, 6878.8624227516175, 6881.587104773729, 6952.0, 7004.544704594185, 7015.859682806417, 7021.017057611836, 7021.964890175721, 6410.358964103552, 6528.118703974903, 6748.089007983859, 6795.966020169867, 6817.5909445433435, 5838.32859221178, 5957.493613042767, 6489.123968264413, 6733.124693789776, 6743.9430973553535, 6444.516661250331, 6640.501962352746, 6642.634655094016, 6646.667666616747, 6665.0668906352175, 6459.128017717548, 6483.2544257411, 6695.464362851058, 6705.161854768154, 6714.362135597111, 5323.866903327448, 6108.816735497942, 6517.283098859027, 6517.543771935919, 6542.640154791532, 5627.606067575231, 6390.105385246162, 6475.125858009108, 6494.090827284279, 6555.541112122106, 5959.493443057216, 6383.872322553616, 6511.38141876521, 6516.413522782972, 6525.347465253437], "value": 6118.123283013276, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.6927882452895, 754.1440465072145, 829.7925518620343, 1224.1859163656163, 1291.1671971578326, 38934.22131557367, 40442.19115617686, 40486.7975660122, 40489.19021619566, 40667.796661016655, 40914.386284205684, 40991.59008409915, 41372.58627413733, 41408.17183656325, 41698.583014169846, 7930.286274235304, 8102.837943241132, 8423.789405264855, 8545.931758530183, 8592.226699597035, 7189.8202544936275, 8464.66141354345, 8489.0, 8589.742307730747, 8617.009043959932, 8582.055973842873, 8635.568221588926, 8638.870416943751, 8656.0, 8659.04750477447, 8587.184217499327, 8590.398672092955, 8600.8709869352, 8607.225349718556, 8631.136886311382, 8422.915770842274, 8423.031351394608, 8469.068402475723, 8507.532085735344, 8511.27654149398, 37821.81089094564, 37943.4308485373, 38096.0, 38172.0, 38218.23563528742, 3617.222297206113, 3638.0904773806546, 3650.908727281839, 3653.0502069461863, 3653.4885116083506, 31026.379472410652, 31142.0, 31171.688283117106, 31183.84408077945, 31402.685973140204, 3071.2014876132143, 3118.937621247585, 3128.5307203919365, 3138.246820763023, 3138.5919830422126, 307050.0, 308902.0, 309700.0, 316133.41933290416, 318078.0, 48122.75938620354, 48651.513484865405, 48652.27021594679, 48765.512344876806, 48875.0, 50410.0, 50456.49543504518, 54518.0, 55590.72204638951, 55767.0, 14244.572662820132, 14290.642733931527, 14357.353919073666, 14418.062825916282, 14443.78334324986, 11403.0, 11515.942420287845, 11530.077593792477, 11531.538738450368, 11532.538698452136, 14253.857461425254, 14255.002149849619, 14286.999910006407, 14311.78532322016, 14336.49822256216, 5293.973530132324, 5600.383957764642, 6211.3478084801345, 6232.127502149703, 6285.685715714196, 942.3639043645512, 1015.4212099103588, 1019.1235537437946, 1047.2093569355088, 1062.166199533378, 25142.742862856783, 25215.243542693748, 25465.745342546707, 25491.49017019649, 25506.576875196828, 1111.6553868300853, 1114.793763153803, 1149.6665966869682, 1151.6372342712018, 1164.4410682872099, 10855.565777368816, 12510.18683785551, 12913.03152263584, 13015.804762928563, 13040.087193896336, 312668.0, 329951.0, 330215.0, 331330.0, 331406.0, 54656.90686186254, 55084.44915550874, 55128.0, 55428.72285638546, 55488.72255638696, 1474.4913005013252, 1528.3275358842075, 1528.4497580870654, 1534.915579643108, 1554.8911576189557, 24700.131941945485, 30359.69640303657, 30382.088537343916, 30704.38591228118, 31055.84472077625, 54076.377708668806, 54128.0, 54363.18455223096, 54376.0, 54558.0, 285543.0, 285885.0, 286060.0, 286743.0, 288025.5598721993, 35944.24892017265, 37219.81390093032, 37275.0, 37297.25405491929, 37326.25347493089, 44516.10967780691, 45161.77419112883, 45438.318425222984, 47011.764941175075, 47155.0, 28029.719702803526, 30204.24489387695, 30401.23996900095, 30951.69048309488, 31950.201244969063, 25625.23124306274, 29121.689523971047, 30664.693353067076, 30720.231994200323, 31680.683193167773, 38369.42445863259, 38435.6156438432, 38459.61540384672, 38508.80745596254, 38809.6119038806, 27851.0, 29095.272618184714, 29915.102546923627, 32150.678493214768, 32282.515762264055, 22839.88580057089, 23179.4205144866, 24991.0, 25055.624165637164, 25360.0], "value": 18149.648936874244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.70643962105754, 436.50674737546575, 439.73396095362307, 458.7270574008466, 459.6851156957484, 19893.00534973251, 21268.57462850744, 21932.561348773015, 22064.227752028663, 22149.335519934426, 22183.0, 22862.77137228627, 23447.0, 23455.882720586374, 23497.177598784045, 5985.730642121115, 6092.208012958309, 6165.229346331708, 6183.876322473548, 6184.907226391608, 5169.948300516984, 5813.215215945836, 6044.486218671418, 6107.81676549702, 6108.786192483264, 5985.820425387224, 6001.549883758714, 6006.069059295816, 6059.939400605982, 6077.969610151943, 5854.677992710399, 6040.758369665252, 6047.092936059582, 6051.304100028509, 6068.029115341568, 4743.857684269477, 5254.08053590624, 5376.1398176292, 5399.757010934517, 5409.891802163935, 20061.79938200614, 26247.868760656267, 26727.732722672914, 27208.727912720817, 27328.59007114895, 2141.593097311506, 2188.682641017044, 2196.352076137527, 2213.4245096275085, 2215.3132528915926, 17018.319267229297, 17297.827021729747, 17764.733528997076, 17911.0, 17957.64084718298, 2153.138744502196, 2164.945876353088, 2201.0535469748, 2201.900914458853, 2206.7131272934416, 162896.0, 162954.0, 166137.0, 167257.0, 167272.0, 12378.381080945917, 12449.688757780947, 12703.809442858364, 12741.808872866914, 12776.6805829855, 16388.75416868748, 16643.50069497917, 16645.918015328964, 16653.41713040062, 16743.497695068923, 6675.365840245169, 7361.0, 7761.262680045386, 9225.630974760934, 9240.306976976754, 7008.579485230903, 8534.70128545498, 8579.571021449028, 8760.78098047554, 8778.736637900873, 6768.526203165829, 6895.207051189077, 8016.158303378178, 8216.83566328682, 8265.669373225004, 1938.9045189468188, 2070.3685375960476, 2165.7076294700328, 2178.7167668203033, 2338.883055847201, 936.6159874451442, 953.3993584042148, 978.4862946952907, 989.6684610655511, 991.4299277915228, 20586.897065514575, 21717.45706357322, 21738.456538586663, 22022.155125872283, 25095.0, 885.526243459746, 921.3964853021148, 997.7755005123789, 1015.2030655935056, 1033.705393962717, 10696.625618103484, 11357.716057098474, 11360.886391135982, 11436.771264574829, 11651.708707282216, 528552.0, 529093.0, 535192.0, 546145.0, 552922.0, 129220.0, 130245.69754302525, 130876.0, 133962.0, 140049.0, 919.7148883846029, 1228.0421271408316, 1237.1834589171103, 1238.139493052338, 1239.7334573066835, 22842.42893927665, 22861.31416057521, 22930.0, 23115.537689246456, 23258.534829302982, 42985.0, 48784.0, 53053.0, 57878.0, 59128.52178695567, 79604.60197698974, 79965.0, 81159.59420202862, 82166.58916705378, 82275.17724822676, 8885.378023538291, 8927.553622318728, 9177.678781242625, 9253.58358873839, 9411.670591529264, 11989.561252649737, 13352.599422017354, 14169.291535423394, 14569.9801013928, 15053.924730376279, 10957.780844383227, 11441.656750297505, 12061.819072714092, 12091.334976576372, 12310.507579696712, 11503.53985840557, 11514.712132196762, 11543.711407214887, 11575.710607234887, 11916.404179791149, 11960.34218117977, 12273.69315767113, 12333.506659733868, 12399.776058225107, 12459.314737689514, 8407.789805254917, 9049.095090490899, 9056.275497960016, 9194.908050919672, 9303.0, 11900.583479578174, 12041.277523348626, 12051.216670916363, 12111.0, 12148.574799882315], "value": 10483.538222064719, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.6466601016743, 1431.1198612853093, 1444.4222311075569, 1449.7535418978787, 1467.2150399536247, 11555.19113662044, 11804.940975295121, 11981.460834262458, 12013.099017573684, 12414.565490207844, 11969.760604787922, 12000.0, 12171.14801963863, 12181.025517958564, 12253.081018923594, 2225.1655629139073, 2525.9621105683427, 2540.64430979663, 2574.034736973635, 2585.4958283134797, 2151.8278537716947, 2504.1594428095336, 2561.0, 2562.436913482774, 2563.0644814642706, 2489.3776555861036, 2509.7992160627196, 2536.201096654557, 2552.668153140089, 2559.462512872299, 2456.705195376557, 2494.0148641286683, 2507.18516482144, 2517.5720127578174, 2520.5841036229, 2438.366024833538, 2491.5639763041577, 2538.1877799104295, 2540.5173017126685, 2564.871756412172, 11723.648290551211, 11864.644060678194, 11971.880281197164, 11985.0, 12017.819732704016, 5670.347909990322, 6576.473882089469, 6778.559393639398, 6854.451643868479, 6859.5541289816, 3981.2634662587225, 4302.161078589676, 4366.6506679465565, 4381.518033016366, 4387.714798538085, 1221.9572314969023, 1225.8528976522875, 1246.339440096741, 1282.3780466473752, 1289.2135797163724, 15860.127692976881, 16353.672926541403, 16376.83623163765, 16458.506244812674, 16461.176941152895, 5375.16684913839, 5467.234587157801, 5613.522850557667, 5630.5495560355075, 5671.9790437720785, 11577.884221157849, 11656.125790565742, 11709.590164344378, 11723.062155027577, 11791.88208117925, 6525.706343214567, 6536.901946470807, 6543.345665433514, 6543.640099794462, 6556.672166391661, 5795.768169273182, 6160.199174107337, 6186.690665466709, 6239.313675495692, 6281.811545653638, 4258.1057977825, 4378.387025816388, 4380.3210502372185, 4412.889677758082, 4421.46942366918, 1244.1415423357855, 1266.050462153385, 1268.1313300389263, 1288.4524077267124, 1294.3010774181837, 637.4708991537013, 754.1402800807084, 756.2513112019061, 798.9920100799034, 814.0638265994154, 6072.605280656743, 6113.144159817629, 6223.751049958041, 6319.58922670025, 6418.903716444257, 590.2945979554391, 599.556328317051, 602.881654530839, 617.4967401567798, 619.4765423217374, 4039.3537034074407, 4080.510338759368, 4326.501315894367, 4411.073674528385, 4443.511213766483, 10515.001074897873, 14573.854261457462, 14698.191599461918, 15018.624534386508, 15088.924555377373, 13031.283279419533, 13576.1854288743, 13727.862721372658, 14962.551123466314, 14982.850171498363, 6816.897746533806, 8513.872291915626, 8537.871931920901, 8597.312215022783, 8665.696700615574, 5900.911486327623, 6089.725962331794, 6590.275069742421, 6737.663116844236, 6915.93084069173, 13218.0, 13390.732185356434, 13518.39167237457, 13613.931930340284, 13684.178949263076, 11473.36896470702, 13849.930750346184, 13967.441302347792, 14010.509632162826, 14027.438902443788, 9272.0, 9480.241580673668, 9483.810323793623, 9509.286803489627, 9510.85733713981, 10605.310654807412, 10611.522481488197, 10676.62631807883, 10798.730031748955, 10801.67594972152, 7708.576028318272, 8369.455985361175, 9171.128742769426, 9225.58484868196, 9267.814643707223, 7136.3220494052985, 9004.41471304363, 9059.22996545301, 9068.863967040368, 9113.453192808453, 8267.421280510307, 8291.709790157556, 8404.621792019252, 8410.789730256793, 8430.030546487233, 5301.098813201688, 5885.323187833454, 6020.157177994996, 6080.361562035922, 6226.937730622636, 6084.421979911901, 6232.906506402498, 6285.685715714105, 6303.527235457451, 6325.177726895474], "value": 5287.852866635661, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.24331580155484, 401.45803165726267, 404.39139095661045, 405.80318545505435, 406.2200574896199, 5039.748012599373, 5311.787528498855, 5736.967345877741, 6043.516518678506, 6229.221347331583, 5247.950409918022, 5580.386157522679, 5788.382261086821, 5997.820065398045, 6156.5382596305235, 3540.9291814163703, 4297.871063868073, 4766.642501812361, 4768.23708206687, 4813.90372192556, 4025.81883815229, 4639.907201855961, 4739.289106633998, 4749.382580264561, 4771.618270538349, 4363.716358436692, 4433.711808732438, 4717.952820471803, 4729.219678753019, 4748.453927798313, 4738.739369334702, 4743.952560474403, 4753.524647535253, 4756.643251756098, 4761.833335833229, 3438.82805859706, 4132.628063474302, 4215.494140703105, 4228.556001619847, 4233.957660423387, 4921.950780492185, 5944.989351810203, 5959.649455897219, 5975.850603734942, 5987.760489580412, 5625.71871406428, 5643.347626615484, 5649.745761440745, 5654.688992105433, 5685.800996965087, 5995.970020149915, 6240.0, 6485.156929599169, 6573.539852210347, 6605.339466053356, 4220.831166753326, 4372.2130016597, 4384.802683879201, 4390.8902277443, 4401.515833258339, 6118.143459915571, 6742.4268937140405, 6929.688164032631, 6993.930060699429, 7000.7549735759, 3447.190539710128, 3806.5812760596314, 4179.791010449465, 4318.762468064224, 4401.877521232065, 3764.661180493769, 3940.2119576084897, 4446.644268458515, 4490.753008584559, 4497.8200871964755, 4361.716488428306, 5098.832995976769, 5266.262723218752, 5315.867103322448, 5364.8122315718765, 5421.783128674888, 6352.110704501373, 6422.9036564450635, 6619.172603424572, 6649.966750166218, 1261.8990480761518, 1485.685168625774, 1517.8861585381142, 1523.5505525869894, 1531.257340190007, 842.8356470488319, 970.4371464550685, 990.4993934189708, 994.6121012805012, 1008.3848852200152, 5646.632968857011, 6175.3515880832765, 6176.413240742122, 6189.690515474208, 6219.751209951642, 5516.9448305517235, 5668.404817494104, 6502.262307853639, 6523.836904077341, 6899.586024838526, 5472.418133503804, 6007.279126504847, 6170.938290617037, 6188.195534580475, 6283.717232724538, 3269.918252043718, 3336.91550246171, 3577.0, 3610.4042832932796, 3642.216923361437, 5869.589128760946, 6973.803251975612, 7044.295570443017, 7057.223705392403, 7073.434125269966, 7019.859602808017, 7078.787636370917, 7095.0, 7114.217436082027, 7125.750598729123, 6139.877202456015, 6513.348665133449, 6848.452123830181, 6982.581045137206, 7058.329458701415, 6166.520035191612, 6193.916064688571, 6364.236291645032, 6530.836729081621, 6846.212685541226, 5068.72122033277, 6696.052599177484, 6702.899456508058, 6715.764948226789, 6721.865562688621, 5835.533157347487, 5985.6408615483215, 6813.625250611264, 7084.103742941465, 7134.431604622228, 3796.37359835624, 4312.654987601047, 4400.317950717644, 4400.691951563488, 4405.229084910064, 6623.900641490478, 6679.265280819203, 6822.488313376417, 6862.4853136016, 6883.418717980757, 5530.281063461885, 5965.850853728692, 6055.304514735881, 6646.0, 6706.966465167643, 5355.812546560852, 6682.365175308338, 6692.631905245258, 6693.698783554849, 6714.462842972453, 6393.776217832354, 6507.381798729113, 6556.967215163893, 6577.704003319958, 6583.901241481286, 6678.332166783282, 6701.195856497252, 6705.1283333166375, 6729.663516824041, 6766.695498702472, 6305.022721478178, 6352.6506042166275, 6462.224533056064, 6521.087047813215, 6544.312847150981], "value": 4759.723802845659, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.8583162115008, 835.3567752830317, 851.9914800851993, 870.6430363550943, 874.4359887872323, 13311.20132792032, 16635.58411039723, 16657.000579965195, 18559.72160417595, 19155.80844191558, 14654.63363415915, 15885.649719773804, 17401.91299043503, 18192.90903545484, 18555.165017574207, 990.7919336939233, 1006.4011912911842, 1010.7927874785652, 1011.6054738651932, 1015.6445244164557, 1010.2019404670301, 1011.8431643095332, 1012.4583347908871, 1020.4948550467492, 1022.2946167144684, 1058.2169194795833, 1071.7213524483611, 1073.9140868730522, 1081.0702795595782, 1081.2053140941402, 1068.727474494003, 1070.9892901071007, 1073.2004656530855, 1073.2809017957986, 1073.7369344510566, 738.7192866710669, 788.8619491589006, 794.047143427883, 794.2811755361407, 794.988075178867, 17090.48728538146, 17206.483805485856, 17501.47495575135, 17565.648687026187, 17651.117444127744, 16987.66024679512, 17320.826791732048, 17435.30258789647, 17535.123243837756, 17564.38524651644, 14302.141871487744, 14526.636834079232, 14563.0, 14570.417183312655, 14632.048916820373, 3625.583057948343, 3634.2186429917697, 3651.926961460756, 3652.3790955537625, 3657.5062366580437, 126277.73722262752, 126823.0, 127781.0, 129167.0, 130193.99999999905, 11837.408129593658, 12447.688807779878, 13261.66845828862, 13432.462701492026, 13507.324633768469, 24977.62533561998, 25452.618210726854, 25845.0, 26068.8696556516, 26272.73727262741, 7529.2470752925365, 7623.618819059025, 7667.8849817252785, 7679.308862202429, 7680.654370553339, 3847.153626202231, 3950.545687245946, 3972.781497017634, 3975.20495900821, 3978.124812541236, 4350.086481838786, 4388.605025547652, 4427.933580996288, 4439.711418757771, 4442.644588432918, 875.1511034296722, 892.2460520859863, 893.8748575199477, 895.0780695883251, 896.7040876510736, 644.3427703742251, 654.3227759269133, 655.3282885042773, 671.9832004199934, 672.7847088931495, 11240.606578769868, 11657.358845263592, 11798.646040618794, 11954.282743035272, 12233.510659573518, 549.3325609384617, 612.8192183305936, 663.4791688524485, 683.729926678959, 695.391532409142, 5038.445770965191, 5456.399796022434, 5532.723363831792, 5635.520980716603, 5654.604177707521, 101452.0, 101613.0, 102757.97242027633, 103466.96533034724, 104175.47912260391, 97380.51309743548, 98262.0, 98724.50637746765, 98925.0, 100218.0, 77869.61065194638, 86102.13897861066, 86874.82812929727, 87420.0, 87850.56074719585, 8576.013758417612, 10050.547725352522, 10268.127209187303, 10504.686914135733, 10655.20085993569, 37633.0, 38633.840984770504, 39069.60930390773, 39505.604943951344, 39938.400923986745, 56506.0, 72025.0, 72042.91935621075, 72136.63931680308, 75210.6239468821, 16522.83477165213, 16806.66386672284, 16887.66224675475, 16940.915295423936, 16979.0, 17197.484075477754, 18103.90948045304, 18165.0, 18432.07839608041, 18449.81550184481, 16414.09722465276, 16477.91761041187, 17072.146392680566, 17134.485965421056, 17157.57106072358, 15290.694186115992, 15352.23238838076, 16354.018758874505, 16678.916605416896, 16998.57503562421, 15976.760348595013, 16508.174591270148, 16560.254788534785, 16625.168741562633, 16741.414050508174, 11886.702832429259, 11900.0, 12136.271823690608, 12250.448729807002, 12340.625218407373, 12858.357082146042, 12910.548130815376, 12964.0, 13037.282949437871, 13093.214407135602], "value": 8461.245452237767, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 116.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 110.0, 110.0, 151.0, 110.0, 108.0, 112.0, 111.0, 136.0, 109.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 202.0, 198.0, 204.0, 203.0, 251.0, 243.0, 199.0, 202.0, 195.0], "value": 209.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 69.0, 112.0, 84.0, 76.0, 75.0, 87.0, 74.0, 80.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 97.0, 67.0, 75.0, 54.0, 73.0, 84.0, 64.0, 79.0, 112.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 77.0, 136.0, 119.0, 88.0, 96.0, 89.0, 85.0, 134.0], "value": 99.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 153.0, 96.0, 97.0, 96.0, 97.0, 97.0, 135.0, 145.0, 96.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 90.0, 91.0, 136.0, 87.0, 138.0, 147.0, 89.0, 90.0], "value": 105.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 54.0, 44.0, 44.0, 71.0, 81.0, 77.0, 68.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 65.0, 56.0, 61.0, 60.0, 59.0, 57.0, 56.0, 56.0, 55.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 85.0, 95.0, 110.0, 105.0, 110.0, 82.0, 81.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 164.0, 130.0, 122.0, 166.0, 160.0, 158.0, 160.0, 205.0, 137.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [238.0, 237.0, 235.0, 182.0, 178.0, 181.0, 189.0, 187.0, 177.0, 186.0], "value": 199.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 82.0, 99.0, 98.0, 93.0, 69.0, 66.0, 66.0, 71.0], "value": 78.2, "unit": "score"}]}]} 18:22:20 INFO - Return code: 0 18:22:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:22:20 INFO - # TBPL SUCCESS # 18:22:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:20 INFO - 2017-05-11 18:22:20.795 screenresolution[2787:41265] starting screenresolution argv=screenresolution get 18:22:20 INFO - 2017-05-11 18:22:20.798 screenresolution[2787:41265] Display 0: 1600x1200x32@60 18:22:20 INFO - 2017-05-11 18:22:20.805 screenresolution[2788:41268] starting screenresolution argv=screenresolution list 18:22:20 INFO - Available Modes on Display 0 18:22:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:21 INFO - Intel Iris: 18:22:21 INFO - Chipset Model: Intel Iris 18:22:21 INFO - Type: GPU 18:22:21 INFO - Bus: Built-In 18:22:21 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:21 INFO - Vendor: Intel (0x8086) 18:22:21 INFO - Device ID: 0x0a2e 18:22:21 INFO - Revision ID: 0x0009 18:22:21 INFO - Displays: 18:22:21 INFO - Display: 18:22:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:21 INFO - Display Serial Number: 1600x1200 60 18:22:21 INFO - Main Display: Yes 18:22:21 INFO - Mirror: Off 18:22:21 INFO - Online: Yes 18:22:21 INFO - Rotation: Supported 18:22:21 INFO - Return code: 0 18:22:21 INFO - Running post-action listener: _resource_record_post_action 18:22:21 INFO - [mozharness: 2017-05-12 01:22:21.050486Z] Finished run-tests step (success) 18:22:21 INFO - Running post-run listener: _resource_record_post_run 18:22:21 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 8704; Write bytes: 527390720; Read time: 1; Write time: 1749 18:22:21 INFO - TinderboxPrint: CPU usage
54.1% 18:22:21 INFO - TinderboxPrint: I/O read bytes / time
8,704 / 1 18:22:21 INFO - TinderboxPrint: I/O write bytes / time
527,390,720 / 1,749 18:22:21 INFO - TinderboxPrint: CPU idle
2,198.7 (45.9%) 18:22:21 INFO - TinderboxPrint: CPU system
964.7 (20.2%) 18:22:21 INFO - TinderboxPrint: CPU user
1,622.2 (33.9%) 18:22:21 INFO - TinderboxPrint: Swap in / out
212,570,112 / 0 18:22:21 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204655104; Write bytes: 206300672; Read time: 16044; Write time: 421 18:22:21 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 8192; Write bytes: 311149056; Read time: 1; Write time: 1291 18:22:21 INFO - Running post-run listener: _upload_blobber_files 18:22:21 INFO - Blob upload gear active. 18:22:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:21 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 18:22:21 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:22:21 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:22:21 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:22:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:22:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:22 INFO - (blobuploader) - INFO - Done attempting. 18:22:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:22:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:22:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:23 INFO - (blobuploader) - INFO - Done attempting. 18:22:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:22:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:22:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:24 INFO - (blobuploader) - INFO - Done attempting. 18:22:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:22:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:26 INFO - (blobuploader) - INFO - Done attempting. 18:22:26 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:26 INFO - Return code: 0 18:22:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb1a9fa06b00c0754f2855506ff59c169e86cf2e2c56a656f4317465fe62f1c4c608d7d82f6a7cb41b30f92150cae66d90503b38dc1baf7eda18cef4fde67967", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58631f507e8100566d8e2cbabce6b24f361bc90b1437198781e07c89da9b11fe9963b81861964cb0c2e21a52bddb1ebc39efc7e5814f7975ed3626dd70255195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b8fcfa35e38afa2e7008945008b42e60aaecef871b3d4d1a045a2c714589ecda968abc95370a81ec229eface8fdee4f4ec5741bfb0c44fbf26d0ffb04579c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53057abf9d813b37e470adabdd617df8281e1e79acb9b1498ac6fe138e547d5b571d913f5209164a7b5b4e573bab51db18016f9e7bf0e09aed986831c0b27914"} 18:22:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:26 INFO - Contents: 18:22:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb1a9fa06b00c0754f2855506ff59c169e86cf2e2c56a656f4317465fe62f1c4c608d7d82f6a7cb41b30f92150cae66d90503b38dc1baf7eda18cef4fde67967", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58631f507e8100566d8e2cbabce6b24f361bc90b1437198781e07c89da9b11fe9963b81861964cb0c2e21a52bddb1ebc39efc7e5814f7975ed3626dd70255195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b8fcfa35e38afa2e7008945008b42e60aaecef871b3d4d1a045a2c714589ecda968abc95370a81ec229eface8fdee4f4ec5741bfb0c44fbf26d0ffb04579c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53057abf9d813b37e470adabdd617df8281e1e79acb9b1498ac6fe138e547d5b571d913f5209164a7b5b4e573bab51db18016f9e7bf0e09aed986831c0b27914"} 18:22:26 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:26 INFO - Copying logs to upload dir... 18:22:26 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.842884 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-11 18:22:26.878661) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 18:22:26.882383) ========= 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.FqyAsb5yki/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.NhT9SoXwUV/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/Firefox/sha512/eb1a9fa06b00c0754f2855506ff59c169e86cf2e2c56a656f4317465fe62f1c4c608d7d82f6a7cb41b30f92150cae66d90503b38dc1baf7eda18cef4fde67967", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58631f507e8100566d8e2cbabce6b24f361bc90b1437198781e07c89da9b11fe9963b81861964cb0c2e21a52bddb1ebc39efc7e5814f7975ed3626dd70255195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b8fcfa35e38afa2e7008945008b42e60aaecef871b3d4d1a045a2c714589ecda968abc95370a81ec229eface8fdee4f4ec5741bfb0c44fbf26d0ffb04579c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53057abf9d813b37e470adabdd617df8281e1e79acb9b1498ac6fe138e547d5b571d913f5209164a7b5b4e573bab51db18016f9e7bf0e09aed986831c0b27914"} build_url:https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011508 build_url: 'https://queue.taskcluster.net/v1/task/BAvyJcF9RI-S6FkExrIenA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb1a9fa06b00c0754f2855506ff59c169e86cf2e2c56a656f4317465fe62f1c4c608d7d82f6a7cb41b30f92150cae66d90503b38dc1baf7eda18cef4fde67967", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58631f507e8100566d8e2cbabce6b24f361bc90b1437198781e07c89da9b11fe9963b81861964cb0c2e21a52bddb1ebc39efc7e5814f7975ed3626dd70255195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b8fcfa35e38afa2e7008945008b42e60aaecef871b3d4d1a045a2c714589ecda968abc95370a81ec229eface8fdee4f4ec5741bfb0c44fbf26d0ffb04579c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53057abf9d813b37e470adabdd617df8281e1e79acb9b1498ac6fe138e547d5b571d913f5209164a7b5b4e573bab51db18016f9e7bf0e09aed986831c0b27914"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 18:22:26.916507) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:22:26.916831) ========= 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.FqyAsb5yki/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.NhT9SoXwUV/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005049 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:22:26.966026) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 18:22:26.966341) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 18:22:26.966670) ========= ========= Total master_lag: 0.17 =========