builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0014 starttime: 1496140036.04 results: success (0) revision: 0c712d76d598ec92a7adaaf7180fd4e559f9abc0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.042093) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.042534) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.042865) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.066900) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.067679) ========= 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.DKNLjntTqc/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.0pRBHY3QQO/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-30 03:27:16-- 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% 31.5M=0s 2017-05-30 03:27:16 (31.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.590560 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.680062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.680381) ========= 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.DKNLjntTqc/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.0pRBHY3QQO/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.022259 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:16.730168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 03:27:16.730545) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0c712d76d598ec92a7adaaf7180fd4e559f9abc0 --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 0c712d76d598ec92a7adaaf7180fd4e559f9abc0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DKNLjntTqc/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.0pRBHY3QQO/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-30 03:27:16,803 truncating revision to first 12 chars 2017-05-30 03:27:16,803 Setting DEBUG logging. 2017-05-30 03:27:16,803 attempt 1/10 2017-05-30 03:27:16,803 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0c712d76d598?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 03:27:17,881 unpacking tar archive at: mozilla-central-0c712d76d598/testing/mozharness/ program finished with exit code 0 elapsedTime=1.334702 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 03:27:18.087359) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:18.087711) ========= script_repo_revision: 0c712d76d598ec92a7adaaf7180fd4e559f9abc0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:18.088575) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:18.088890) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 03:27:18.117270) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-30 03:27:18.117780) ========= /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.DKNLjntTqc/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.0pRBHY3QQO/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:27:18 INFO - MultiFileLogger online at 20170530 03:27:18 in /builds/slave/test 03:27:18 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 03:27:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:27:18 INFO - {'append_to_log': False, 03:27:18 INFO - 'base_work_dir': '/builds/slave/test', 03:27:18 INFO - 'blob_upload_branch': 'Firefox', 03:27:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:27:18 INFO - 'branch': 'Firefox', 03:27:18 INFO - 'buildbot_json_path': 'buildprops.json', 03:27:18 INFO - 'config_files': ('talos/mac_config.py',), 03:27:18 INFO - 'default_actions': ('clobber', 03:27:18 INFO - 'read-buildbot-config', 03:27:18 INFO - 'download-and-extract', 03:27:18 INFO - 'populate-webroot', 03:27:18 INFO - 'create-virtualenv', 03:27:18 INFO - 'install', 03:27:18 INFO - 'setup-mitmproxy', 03:27:18 INFO - 'run-tests'), 03:27:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:27:18 INFO - 'download_minidump_stackwalk': True, 03:27:18 INFO - 'download_symbols': 'ondemand', 03:27:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:27:18 INFO - 'tooltool.py': '/tools/tooltool.py', 03:27:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:27:18 INFO - '/tools/misc-python/virtualenv.py')}, 03:27:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:27:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:27:18 INFO - 'gecko_profile': False, 03:27:18 INFO - 'gecko_profile_interval': 0, 03:27:18 INFO - 'installer_path': 'installer.exe', 03:27:18 INFO - 'log_level': 'info', 03:27:18 INFO - 'log_name': 'talos', 03:27:18 INFO - 'log_to_console': True, 03:27:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:27:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:27:18 INFO - 'opt_config_files': (), 03:27:18 INFO - 'pip_index': False, 03:27:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:18 INFO - 'cmd': ('bash', 03:27:18 INFO - '-c', 03:27:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:18 INFO - 'enabled': True, 03:27:18 INFO - 'halt_on_failure': False, 03:27:18 INFO - 'name': 'check_screen_resolution'},), 03:27:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:18 INFO - 'cmd': ('bash', 03:27:18 INFO - '-c', 03:27:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:18 INFO - 'enabled': True, 03:27:18 INFO - 'halt_on_failure': False, 03:27:18 INFO - 'name': 'check_screen_resolution'},), 03:27:18 INFO - 'run_cmd_checks_enabled': True, 03:27:18 INFO - 'suite': 'dromaeojs-e10s', 03:27:18 INFO - 'system_bits': '32', 03:27:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:27:18 INFO - 'title': 't-yosemite-r7-0014', 03:27:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:27:18 INFO - 'use_talos_json': True, 03:27:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:27:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:27:18 INFO - 'work_dir': 'build'} 03:27:18 INFO - [mozharness: 2017-05-30 10:27:18.323408Z] Running clobber step. 03:27:18 INFO - Running pre-action listener: _resource_record_pre_action 03:27:18 INFO - Running main action method: clobber 03:27:18 INFO - rmtree: /builds/slave/test/build 03:27:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:27:20 INFO - Running post-action listener: _resource_record_post_action 03:27:20 INFO - [mozharness: 2017-05-30 10:27:20.092040Z] Finished clobber step (success) 03:27:20 INFO - [mozharness: 2017-05-30 10:27:20.092218Z] Running read-buildbot-config step. 03:27:20 INFO - Running pre-action listener: _resource_record_pre_action 03:27:20 INFO - Running main action method: read_buildbot_config 03:27:20 INFO - Using buildbot properties: 03:27:20 INFO - { 03:27:20 INFO - "project": "", 03:27:20 INFO - "product": "firefox", 03:27:20 INFO - "who": "cbook@mozilla.com", 03:27:20 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:27:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:27:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:27:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:27:20 INFO - "stage_platform": "macosx64", 03:27:20 INFO - "basedir": "/builds/slave/test", 03:27:20 INFO - "buildnumber": 36, 03:27:20 INFO - "platform": "yosemite_r7", 03:27:20 INFO - "parent_task_id": "AXesgECUT8e97XpEPAzAjg", 03:27:20 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:27:20 INFO - "slavebuilddir": "test", 03:27:20 INFO - "taskId": "V1aAkr4XTyK-_EFxP0Cn7g", 03:27:20 INFO - "branch": "mozilla-central", 03:27:20 INFO - "script_repo_revision": "production", 03:27:20 INFO - "revision": "0c712d76d598ec92a7adaaf7180fd4e559f9abc0", 03:27:20 INFO - "slavename": "t-yosemite-r7-0014", 03:27:20 INFO - "repo_path": "mozilla-central" 03:27:20 INFO - } 03:27:20 INFO - Finding installer, test and symbols from parent task. 03:27:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V1aAkr4XTyK-_EFxP0Cn7g'}, attempt #1 03:27:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V1aAkr4XTyK-_EFxP0Cn7g'}, attempt #1 03:27:21 INFO - Task dependencies: AS00F6YsTvmJbKxRkDmjOA AXesgECUT8e97XpEPAzAjg 03:27:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg'}, attempt #1 03:27:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:27:21 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/test_packages.json 03:27:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/target.crashreporter-symbols.zip 03:27:21 INFO - Running post-action listener: _resource_record_post_action 03:27:21 INFO - [mozharness: 2017-05-30 10:27:21.784642Z] Finished read-buildbot-config step (success) 03:27:21 INFO - [mozharness: 2017-05-30 10:27:21.784748Z] Running download-and-extract step. 03:27:21 INFO - Running pre-action listener: _resource_record_pre_action 03:27:21 INFO - Running main action method: download_and_extract 03:27:21 INFO - mkdir: /builds/slave/test/build/tests 03:27:21 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')]} 03:27:21 INFO - trying https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:27:21 INFO - Downloading https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/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 03:27:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/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 03:27:22 INFO - Downloaded 1325 bytes. 03:27:22 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:27:22 INFO - Using the following test package requirements: 03:27:22 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:27:22 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:27:22 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:27:22 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:27:22 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'jsshell-mac.zip'], 03:27:22 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:27:22 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:27:22 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:27:22 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:27:22 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:27:22 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:22 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:27:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:27:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:27:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:27:22 INFO - Fetch https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:27:24 INFO - Content-Length response header: 57630241 03:27:24 INFO - Bytes received: 57630241 03:27:41 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 03:27:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:27:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:27:41 INFO - Fetch https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:27:44 INFO - Content-Length response header: 57630241 03:27:44 INFO - Bytes received: 57630241 03:28:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:28:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:28:01 INFO - Fetch https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:28:02 INFO - Content-Length response header: 14447425 03:28:02 INFO - Bytes received: 14447425 03:28: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')]} 03:28:02 INFO - trying https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:02 INFO - Downloading https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:28:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/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 03:28:04 INFO - Downloaded 67893398 bytes. 03:28:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:04 INFO - mkdir: /builds/slave/test/properties 03:28:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:28:04 INFO - Writing to file /builds/slave/test/properties/build_url 03:28:04 INFO - Contents: 03:28:04 INFO - build_url:https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:04 INFO - Running post-action listener: _resource_record_post_action 03:28:04 INFO - Running post-action listener: set_extra_try_arguments 03:28:04 INFO - [mozharness: 2017-05-30 10:28:04.670513Z] Finished download-and-extract step (success) 03:28:04 INFO - [mozharness: 2017-05-30 10:28:04.670642Z] Running populate-webroot step. 03:28:04 INFO - Running pre-action listener: _resource_record_pre_action 03:28:04 INFO - Running main action method: populate_webroot 03:28:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:28:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:28:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:28:04 INFO - '--tppagecycles', 03:28:04 INFO - '7'], 03:28:04 INFO - 'tests': ['tsvgm']}}, 03:28:04 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:28:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:28:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:28:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['damp', 'tps']}, 03:28:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'tests': ['damp', 'tps']}, 03:28:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['dromaeo_dom']}, 03:28:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:28:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:28:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:28:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:28:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['a11yr', 03:28:04 INFO - 'ts_paint', 03:28:04 INFO - 'tpaint', 03:28:04 INFO - 'sessionrestore', 03:28:04 INFO - 'sessionrestore_no_auto_restore', 03:28:04 INFO - 'tabpaint']}, 03:28:04 INFO - 'other-e10s': {'tests': ['a11yr', 03:28:04 INFO - 'ts_paint', 03:28:04 INFO - 'tpaint', 03:28:04 INFO - 'sessionrestore', 03:28:04 INFO - 'sessionrestore_no_auto_restore', 03:28:04 INFO - 'tabpaint']}, 03:28:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:28:04 INFO - 'ts_paint', 03:28:04 INFO - 'tpaint', 03:28:04 INFO - 'sessionrestore', 03:28:04 INFO - 'sessionrestore_no_auto_restore', 03:28:04 INFO - 'tabpaint']}, 03:28:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:28:04 INFO - 'ts_paint', 03:28:04 INFO - 'tpaint', 03:28:04 INFO - 'sessionrestore', 03:28:04 INFO - 'sessionrestore_no_auto_restore', 03:28:04 INFO - 'tabpaint']}, 03:28:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['a11yr', 03:28:04 INFO - 'ts_paint', 03:28:04 INFO - 'tpaint', 03:28:04 INFO - 'sessionrestore', 03:28:04 INFO - 'sessionrestore_no_auto_restore', 03:28:04 INFO - 'tabpaint']}, 03:28:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['a11yr', 03:28:04 INFO - 'ts_paint', 03:28:04 INFO - 'tpaint', 03:28:04 INFO - 'sessionrestore', 03:28:04 INFO - 'sessionrestore_no_auto_restore', 03:28:04 INFO - 'tabpaint']}, 03:28:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:28:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:28:04 INFO - 'bloom_basic_ref']}, 03:28:04 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 03:28:04 INFO - 'talos_options': ['--mitmproxy', 03:28:04 INFO - 'mitmproxy-recording-1.mp', 03:28:04 INFO - '--firstNonBlankPaint'], 03:28:04 INFO - 'tests': ['Quantum_1']}, 03:28:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['tsvgx', 03:28:04 INFO - 'tsvgr_opacity', 03:28:04 INFO - 'tart', 03:28:04 INFO - 'tscrollx', 03:28:04 INFO - 'cart', 03:28:04 INFO - 'tsvg_static']}, 03:28:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:28:04 INFO - 'tsvgr_opacity', 03:28:04 INFO - 'tart', 03:28:04 INFO - 'tscrollx', 03:28:04 INFO - 'cart', 03:28:04 INFO - 'tsvg_static']}, 03:28:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'talos_options': ['--disable-e10s'], 03:28:04 INFO - 'tests': ['tp5o']}, 03:28:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:28:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'talos_options': ['--disable-e10s', 03:28:04 INFO - '--xperf_path', 03:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:04 INFO - 'tests': ['tp5n']}, 03:28:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:28:04 INFO - 'talos_options': ['--xperf_path', 03:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:04 INFO - 'tests': ['tp5n']}}, 03:28:04 INFO - 'talos.zip': {'path': '', 03:28:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:28:04 INFO - Running post-action listener: _resource_record_post_action 03:28:04 INFO - [mozharness: 2017-05-30 10:28:04.692679Z] Finished populate-webroot step (success) 03:28:04 INFO - [mozharness: 2017-05-30 10:28:04.692823Z] Running create-virtualenv step. 03:28:04 INFO - Running pre-action listener: _resource_record_pre_action 03:28:04 INFO - Running main action method: create_virtualenv 03:28:04 INFO - Creating virtualenv /builds/slave/test/build/venv 03:28:04 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 03:28:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:28:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:28:04 INFO - Using real prefix '/tools/python27' 03:28:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:28:05 INFO - Installing distribute.............................................................................................................................................................................................done. 03:28:08 INFO - Installing pip.................done. 03:28:08 INFO - Return code: 0 03:28:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:28:08 INFO - Reading from file tmpfile_stdout 03:28:08 INFO - Output received: 03:28:08 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) 03:28:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:28:08 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')]} 03:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:08 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')]} 03:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28: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', 'pip>=1.5'] in /builds/slave/test/build 03:28: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 pip>=1.5 03:28:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:08 INFO - 'HOME': '/Users/cltbld', 03:28:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:08 INFO - 'LOGNAME': 'cltbld', 03:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:08 INFO - 'MOZ_NO_REMOTE': '1', 03:28:08 INFO - 'NO_EM_RESTART': '1', 03:28:08 INFO - 'PAGER': '/bin/cat', 03:28:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:08 INFO - 'PWD': '/builds/slave/test', 03:28:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:08 INFO - 'SHELL': '/bin/bash', 03:28:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:08 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:08 INFO - 'USER': 'cltbld', 03:28:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:08 INFO - 'XPC_FLAGS': '0x0', 03:28:08 INFO - 'XPC_SERVICE_NAME': '0', 03:28:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:08 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 03:28:08 INFO - Cleaning up... 03:28:08 INFO - Return code: 0 03:28:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:28:08 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')]} 03:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:08 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')]} 03:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28: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', '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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:28: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 psutil>=3.1.1 03:28:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:08 INFO - 'HOME': '/Users/cltbld', 03:28:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:08 INFO - 'LOGNAME': 'cltbld', 03:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:08 INFO - 'MOZ_NO_REMOTE': '1', 03:28:08 INFO - 'NO_EM_RESTART': '1', 03:28:08 INFO - 'PAGER': '/bin/cat', 03:28:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:08 INFO - 'PWD': '/builds/slave/test', 03:28:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:08 INFO - 'SHELL': '/bin/bash', 03:28:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:08 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:08 INFO - 'USER': 'cltbld', 03:28:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:08 INFO - 'XPC_FLAGS': '0x0', 03:28:08 INFO - 'XPC_SERVICE_NAME': '0', 03:28:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:09 INFO - Downloading/unpacking psutil>=3.1.1 03:28: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 03:28: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 03:28: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 03:28: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 03:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:28:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:11 INFO - Installing collected packages: psutil 03:28:11 INFO - Running setup.py install for psutil 03:28:11 INFO - building 'psutil._psutil_osx' extension 03:28:11 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 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 INFO - building 'psutil._psutil_posix' extension 03:28:12 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 03:28:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:28:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:28:12 INFO - ^ 03:28:12 INFO - 1 warning generated. 03:28:12 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 03:28:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:12 INFO - Successfully installed psutil 03:28:12 INFO - Cleaning up... 03:28:12 INFO - Return code: 0 03:28:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:12 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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:12 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 03:28:12 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 03:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:12 INFO - 'HOME': '/Users/cltbld', 03:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:12 INFO - 'LOGNAME': 'cltbld', 03:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:12 INFO - 'MOZ_NO_REMOTE': '1', 03:28:12 INFO - 'NO_EM_RESTART': '1', 03:28:12 INFO - 'PAGER': '/bin/cat', 03:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:12 INFO - 'PWD': '/builds/slave/test', 03:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:12 INFO - 'SHELL': '/bin/bash', 03:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:12 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:12 INFO - 'USER': 'cltbld', 03:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:12 INFO - 'XPC_FLAGS': '0x0', 03:28:12 INFO - 'XPC_SERVICE_NAME': '0', 03:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:28: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 03:28: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 03:28: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 03:28: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 03:28:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:28:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:28:14 INFO - Installing collected packages: mozsystemmonitor 03:28:14 INFO - Running setup.py install for mozsystemmonitor 03:28:14 INFO - Successfully installed mozsystemmonitor 03:28:14 INFO - Cleaning up... 03:28:14 INFO - Return code: 0 03:28:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28: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')]} 03:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:14 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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:14 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 03:28:14 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 03:28:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:14 INFO - 'HOME': '/Users/cltbld', 03:28:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:14 INFO - 'LOGNAME': 'cltbld', 03:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:14 INFO - 'MOZ_NO_REMOTE': '1', 03:28:14 INFO - 'NO_EM_RESTART': '1', 03:28:14 INFO - 'PAGER': '/bin/cat', 03:28:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:14 INFO - 'PWD': '/builds/slave/test', 03:28:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:14 INFO - 'SHELL': '/bin/bash', 03:28:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:14 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:14 INFO - 'USER': 'cltbld', 03:28:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:14 INFO - 'XPC_FLAGS': '0x0', 03:28:14 INFO - 'XPC_SERVICE_NAME': '0', 03:28:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:14 INFO - Downloading/unpacking jsonschema==2.5.1 03:28:14 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 03:28:14 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 03:28:14 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 03:28:14 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 03:28:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:28:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:16 INFO - Downloading functools32-3.2.3-2.tar.gz 03:28:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:28:16 INFO - warning: no files found matching '*.txt' 03:28:16 INFO - no previously-included directories found matching 'build' 03:28:16 INFO - no previously-included directories found matching 'dist' 03:28:16 INFO - no previously-included directories found matching '.git*' 03:28:16 INFO - Installing collected packages: jsonschema, functools32 03:28:16 INFO - Running setup.py install for functools32 03:28:16 INFO - warning: no files found matching '*.txt' 03:28:16 INFO - no previously-included directories found matching 'build' 03:28:16 INFO - no previously-included directories found matching 'dist' 03:28:16 INFO - no previously-included directories found matching '.git*' 03:28:16 INFO - Successfully installed jsonschema functools32 03:28:16 INFO - Cleaning up... 03:28:16 INFO - Return code: 0 03:28:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:16 INFO - 'HOME': '/Users/cltbld', 03:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:16 INFO - 'LOGNAME': 'cltbld', 03:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:16 INFO - 'MOZ_NO_REMOTE': '1', 03:28:16 INFO - 'NO_EM_RESTART': '1', 03:28:16 INFO - 'PAGER': '/bin/cat', 03:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:16 INFO - 'PWD': '/builds/slave/test', 03:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:16 INFO - 'SHELL': '/bin/bash', 03:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:16 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:16 INFO - 'USER': 'cltbld', 03:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:16 INFO - 'XPC_FLAGS': '0x0', 03:28:16 INFO - 'XPC_SERVICE_NAME': '0', 03:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:28:16 INFO - Cleaning up... 03:28:16 INFO - Return code: 0 03:28:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:16 INFO - 'HOME': '/Users/cltbld', 03:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:16 INFO - 'LOGNAME': 'cltbld', 03:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:16 INFO - 'MOZ_NO_REMOTE': '1', 03:28:16 INFO - 'NO_EM_RESTART': '1', 03:28:16 INFO - 'PAGER': '/bin/cat', 03:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:16 INFO - 'PWD': '/builds/slave/test', 03:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:16 INFO - 'SHELL': '/bin/bash', 03:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:16 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:16 INFO - 'USER': 'cltbld', 03:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:16 INFO - 'XPC_FLAGS': '0x0', 03:28:16 INFO - 'XPC_SERVICE_NAME': '0', 03:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:17 INFO - Downloading/unpacking blobuploader==1.2.4 03:28:17 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 03:28:17 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 03:28:17 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 03:28:17 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 03:28:18 INFO - Downloading blobuploader-1.2.4.tar.gz 03:28:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:28:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:28:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:28:19 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 03:28:19 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 03:28:19 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 03:28:19 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 03:28:19 INFO - Downloading docopt-0.6.1.tar.gz 03:28:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:28:19 INFO - Installing collected packages: blobuploader, requests, docopt 03:28:19 INFO - Running setup.py install for blobuploader 03:28:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:28:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:28:19 INFO - Running setup.py install for requests 03:28:20 INFO - Running setup.py install for docopt 03:28:20 INFO - Successfully installed blobuploader requests docopt 03:28:20 INFO - Cleaning up... 03:28:20 INFO - Return code: 0 03:28:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:20 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')]} 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:20 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')]} 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:20 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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:20 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 03:28:20 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 03:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:20 INFO - 'HOME': '/Users/cltbld', 03:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:20 INFO - 'LOGNAME': 'cltbld', 03:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:20 INFO - 'MOZ_NO_REMOTE': '1', 03:28:20 INFO - 'NO_EM_RESTART': '1', 03:28:20 INFO - 'PAGER': '/bin/cat', 03:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:20 INFO - 'PWD': '/builds/slave/test', 03:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:20 INFO - 'SHELL': '/bin/bash', 03:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:20 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:20 INFO - 'USER': 'cltbld', 03:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:20 INFO - 'XPC_FLAGS': '0x0', 03:28:20 INFO - 'XPC_SERVICE_NAME': '0', 03:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:20 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-oo2Qw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:20 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ytWEYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:20 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-iXccj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:20 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-nbJnwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-AHuIIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-86YmCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-00VM4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-qO1iTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-2ZDQ5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-omHut4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-18o79r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-0HiaqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:21 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-0VMaka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:22 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-EM2yU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:22 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-41tM66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:22 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-mn48mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:22 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ci4llY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:28:22 INFO - Running setup.py install for manifestparser 03:28:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:28:22 INFO - Running setup.py install for mozcrash 03:28:22 INFO - Running setup.py install for mozdebug 03:28:22 INFO - Running setup.py install for mozdevice 03:28:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Running setup.py install for mozfile 03:28:23 INFO - Running setup.py install for mozhttpd 03:28:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Running setup.py install for mozinfo 03:28:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Running setup.py install for mozInstall 03:28:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Running setup.py install for mozleak 03:28:23 INFO - Running setup.py install for mozlog 03:28:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Running setup.py install for moznetwork 03:28:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:28:24 INFO - Running setup.py install for mozprocess 03:28:24 INFO - Running setup.py install for mozprofile 03:28:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:28:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:28:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:28:24 INFO - Running setup.py install for mozrunner 03:28:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:28:24 INFO - Running setup.py install for mozscreenshot 03:28:24 INFO - Running setup.py install for moztest 03:28:24 INFO - Running setup.py install for mozversion 03:28:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:28:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:28:25 INFO - Cleaning up... 03:28:25 INFO - Return code: 0 03:28:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:25 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')]} 03:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:25 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')]} 03:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:25 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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:25 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 03:28:25 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 03:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:25 INFO - 'HOME': '/Users/cltbld', 03:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:25 INFO - 'LOGNAME': 'cltbld', 03:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:25 INFO - 'MOZ_NO_REMOTE': '1', 03:28:25 INFO - 'NO_EM_RESTART': '1', 03:28:25 INFO - 'PAGER': '/bin/cat', 03:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:25 INFO - 'PWD': '/builds/slave/test', 03:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:25 INFO - 'SHELL': '/bin/bash', 03:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:25 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:25 INFO - 'USER': 'cltbld', 03:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:25 INFO - 'XPC_FLAGS': '0x0', 03:28:25 INFO - 'XPC_SERVICE_NAME': '0', 03:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:25 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-0ak4z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:25 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)) 03:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:25 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-4FEu0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:25 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)) 03:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:25 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-3YOQcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:25 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)) 03:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:25 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-GePlgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:25 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-gAfZbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:25 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)) 03:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:25 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-wshLsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:25 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)) 03:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:25 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-LzDGGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-_6Z_rQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-vcKYyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-bDqwpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-jzha8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-AUj3GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-JdcizK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-LI8q4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-zp0CIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:26 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)) 03:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:26 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-H73oLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:27 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)) 03:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:27 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-g3tzKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:27 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)) 03:28:27 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)) 03:28:27 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)) 03:28:27 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)) 03:28:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:28 INFO - Downloading blessings-1.6.tar.gz 03:28:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:28:28 INFO - Installing collected packages: blessings 03:28:28 INFO - Running setup.py install for blessings 03:28:28 INFO - Successfully installed blessings 03:28:28 INFO - Cleaning up... 03:28:28 INFO - Return code: 0 03:28:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:28:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:29 INFO - Reading from file tmpfile_stdout 03:28:29 INFO - Current package versions: 03:28:29 INFO - blessings == 1.6 03:28:29 INFO - blobuploader == 1.2.4 03:28:29 INFO - docopt == 0.6.1 03:28:29 INFO - functools32 == 3.2.3-2 03:28:29 INFO - jsonschema == 2.5.1 03:28:29 INFO - manifestparser == 1.1 03:28:29 INFO - mozInstall == 1.12 03:28:29 INFO - mozcrash == 1.0 03:28:29 INFO - mozdebug == 0.1 03:28:29 INFO - mozdevice == 0.51 03:28:29 INFO - mozfile == 1.2 03:28:29 INFO - mozhttpd == 0.7 03:28:29 INFO - mozinfo == 0.9 03:28:29 INFO - mozleak == 0.1 03:28:29 INFO - mozlog == 3.4 03:28:29 INFO - moznetwork == 0.27 03:28:29 INFO - mozprocess == 0.25 03:28:29 INFO - mozprofile == 0.28 03:28:29 INFO - mozrunner == 6.13 03:28:29 INFO - mozscreenshot == 0.1 03:28:29 INFO - mozsystemmonitor == 0.3 03:28:29 INFO - moztest == 0.8 03:28:29 INFO - mozversion == 1.4 03:28:29 INFO - psutil == 3.1.1 03:28:29 INFO - requests == 1.2.3 03:28:29 INFO - wsgiref == 0.1.2 03:28:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:29 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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:29 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 03:28:29 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 03:28:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:29 INFO - 'HOME': '/Users/cltbld', 03:28:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:29 INFO - 'LOGNAME': 'cltbld', 03:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:29 INFO - 'MOZ_NO_REMOTE': '1', 03:28:29 INFO - 'NO_EM_RESTART': '1', 03:28:29 INFO - 'PAGER': '/bin/cat', 03:28:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:29 INFO - 'PWD': '/builds/slave/test', 03:28:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:29 INFO - 'SHELL': '/bin/bash', 03:28:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:29 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:29 INFO - 'USER': 'cltbld', 03:28:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:29 INFO - 'XPC_FLAGS': '0x0', 03:28:29 INFO - 'XPC_SERVICE_NAME': '0', 03:28:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:29 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)) 03:28:29 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)) 03:28:29 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)) 03:28:29 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)) 03:28:29 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)) 03:28:29 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)) 03:28:29 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)) 03:28:29 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)) 03:28:29 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)) 03:28:29 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28:29 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 03:28:29 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 03:28:29 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 03:28:29 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 03:28:30 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:28:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:28:30 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)) 03:28:30 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)) 03:28:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28:30 INFO - Installing collected packages: marionette-driver 03:28:30 INFO - Running setup.py install for marionette-driver 03:28:31 INFO - Successfully installed marionette-driver 03:28:31 INFO - Cleaning up... 03:28:31 INFO - Return code: 0 03:28:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:28:31 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')]} 03:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:31 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')]} 03:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:31 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 0x10fbe0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e9c84e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, '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.DKNLjntTqc/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.0pRBHY3QQO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 03:28:31 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 03:28:31 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 03:28:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:31 INFO - 'HOME': '/Users/cltbld', 03:28:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:31 INFO - 'LOGNAME': 'cltbld', 03:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:31 INFO - 'MOZ_NO_REMOTE': '1', 03:28:31 INFO - 'NO_EM_RESTART': '1', 03:28:31 INFO - 'PAGER': '/bin/cat', 03:28:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:31 INFO - 'PWD': '/builds/slave/test', 03:28:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:31 INFO - 'SHELL': '/bin/bash', 03:28:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:31 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:31 INFO - 'USER': 'cltbld', 03:28:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:31 INFO - 'XPC_FLAGS': '0x0', 03:28:31 INFO - 'XPC_SERVICE_NAME': '0', 03:28:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:28:31 INFO - Cleaning up... 03:28:31 INFO - Return code: 0 03:28:31 INFO - Running post-action listener: _resource_record_post_action 03:28:31 INFO - Running post-action listener: _start_resource_monitoring 03:28:31 INFO - Starting resource monitoring. 03:28:31 INFO - [mozharness: 2017-05-30 10:28:31.442176Z] Finished create-virtualenv step (success) 03:28:31 INFO - [mozharness: 2017-05-30 10:28:31.443246Z] Running install step. 03:28:31 INFO - Running pre-action listener: _resource_record_pre_action 03:28:31 INFO - Running main action method: install 03:28:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:31 INFO - Reading from file tmpfile_stdout 03:28:31 INFO - Detecting whether we're running mozinstall >=1.0... 03:28:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:28:31 INFO - Reading from file tmpfile_stdout 03:28:31 INFO - Output received: 03:28:31 INFO - Usage: mozinstall [options] installer 03:28:31 INFO - Options: 03:28:31 INFO - -h, --help show this help message and exit 03:28:31 INFO - -d DEST, --destination=DEST 03:28:31 INFO - Directory to install application into. [default: 03:28:31 INFO - "/builds/slave/test"] 03:28:31 INFO - --app=APP Application being installed. [default: firefox] 03:28:31 INFO - mkdir: /builds/slave/test/build/application 03:28:31 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'] 03:28:31 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 03:28:53 INFO - Reading from file tmpfile_stdout 03:28:53 INFO - Output received: 03:28:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:28:53 INFO - Running post-action listener: _resource_record_post_action 03:28:53 INFO - [mozharness: 2017-05-30 10:28:53.066856Z] Finished install step (success) 03:28:53 INFO - [mozharness: 2017-05-30 10:28:53.066988Z] Running setup-mitmproxy step. 03:28:53 INFO - Running pre-action listener: _resource_record_pre_action 03:28:53 INFO - Running main action method: setup_mitmproxy 03:28:53 INFO - Skipping: mitmproxy is not required 03:28:53 INFO - Running post-action listener: _resource_record_post_action 03:28:53 INFO - [mozharness: 2017-05-30 10:28:53.067623Z] Finished setup-mitmproxy step (success) 03:28:53 INFO - [mozharness: 2017-05-30 10:28:53.067745Z] Running run-tests step. 03:28:53 INFO - Running pre-action listener: _resource_record_pre_action 03:28:53 INFO - Running main action method: run_tests 03:28:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:28:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:28:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:28:53 INFO - 2017-05-30 03:28:53.082 screenresolution[3126:47340] starting screenresolution argv=screenresolution get 03:28:53 INFO - 2017-05-30 03:28:53.085 screenresolution[3126:47340] Display 0: 1600x1200x32@60 03:28:53 INFO - 2017-05-30 03:28:53.091 screenresolution[3127:47342] starting screenresolution argv=screenresolution list 03:28:53 INFO - Available Modes on Display 0 03:28:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:28:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:28:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:28:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:28:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:28:53 INFO - Intel Iris: 03:28:53 INFO - Chipset Model: Intel Iris 03:28:53 INFO - Type: GPU 03:28:53 INFO - Bus: Built-In 03:28:53 INFO - VRAM (Dynamic, Max): 1536 MB 03:28:53 INFO - Vendor: Intel (0x8086) 03:28:53 INFO - Device ID: 0x0a2e 03:28:53 INFO - Revision ID: 0x0009 03:28:53 INFO - Displays: 03:28:53 INFO - Display: 03:28:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:28:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:28:53 INFO - Display Serial Number: 1600x1200 60 03:28:53 INFO - Main Display: Yes 03:28:53 INFO - Mirror: Off 03:28:53 INFO - Online: Yes 03:28:53 INFO - Rotation: Supported 03:28:53 INFO - Return code: 0 03:28:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:28:53 INFO - Python 2.7.3 03:28:53 INFO - Return code: 0 03:28:53 INFO - grabbing minidump binary from tooltool 03:28:53 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')]} 03:28:53 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 0x10fb69c60>, '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 0x10f8fecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f927be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:28:53 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 03:28:53 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 03:28:53 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 03:28:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:28:53 INFO - Return code: 0 03:28:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:28:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:28:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:28:53 INFO - ENV: RUST_BACKTRACE is now 1 03:28:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:28:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:28:53 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/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 03:28:53 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/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0014 --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 03:28:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DKNLjntTqc/Render', 03:28:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:53 INFO - 'HOME': '/Users/cltbld', 03:28:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:53 INFO - 'LOGNAME': 'cltbld', 03:28:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:28:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:53 INFO - 'MOZ_NO_REMOTE': '1', 03:28:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:28:53 INFO - 'NO_EM_RESTART': '1', 03:28:53 INFO - 'PAGER': '/bin/cat', 03:28:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:53 INFO - 'PWD': '/builds/slave/test', 03:28:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:28:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:53 INFO - 'RUST_BACKTRACE': '1', 03:28:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:28:53 INFO - 'SHELL': '/bin/bash', 03:28:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0pRBHY3QQO/Listeners', 03:28:53 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 03:28:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:53 INFO - 'USER': 'cltbld', 03:28:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:53 INFO - 'XPC_FLAGS': '0x0', 03:28:53 INFO - 'XPC_SERVICE_NAME': '0', 03:28:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:53 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/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 03:28:53 INFO - mozversion application_buildid: 20170530021923 03:28:53 INFO - mozversion application_changeset: 0c712d76d598ec92a7adaaf7180fd4e559f9abc0 03:28:53 INFO - mozversion application_display_name: Nightly 03:28:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:28:53 INFO - mozversion application_name: Firefox 03:28:53 INFO - mozversion application_remotingname: firefox 03:28:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:28:53 INFO - mozversion application_vendor: Mozilla 03:28:53 INFO - mozversion application_version: 55.0a1 03:28:53 INFO - mozversion platform_buildid: 20170530021923 03:28:53 INFO - mozversion platform_changeset: 0c712d76d598ec92a7adaaf7180fd4e559f9abc0 03:28:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:28:53 INFO - mozversion platform_version: 55.0a1 03:28:53 INFO - using testdate: 1496140133 03:28:53 INFO - actual date: 1496140133 03:28:53 INFO - starting webserver on 'localhost:59582' 03:28:53 INFO - SUITE-START | Running 2 tests 03:28:53 INFO - TEST-START | dromaeo_css 03:28:53 INFO - operating with platform_type : mac_ 03:28:53 INFO - Initialising browser for dromaeo_css test... 03:28:53 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:59582/getInfo.html -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpYHy6Yz/profile 03:28:53 INFO - TEST-INFO | started process 3137 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:59582/getInfo.html -foreground) 03:29:27 INFO - TEST-INFO | 3137: killed by SIGTERM 03:29:27 INFO - Browser initialized. 03:29:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:29:27 INFO - TEST-INFO | started process 3144 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpYHy6Yz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:29:30 INFO - PID 3144 | 2017-05-30 03:29:30.055 plugin-container[3145:47910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 03:29:30 INFO - PID 3144 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:30 INFO - PID 3144 | 2017-05-30 03:29:30.056 plugin-container[3145:47910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 03:29:30 INFO - PID 3144 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:30 INFO - PID 3144 | 2017-05-30 03:29:30.056 plugin-container[3145:47910] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:29:30 INFO - PID 3144 | 2017-05-30 03:29:30.513 plugin-container[3146:47950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 03:29:30 INFO - PID 3144 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:30 INFO - PID 3144 | 2017-05-30 03:29:30.514 plugin-container[3146:47950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 03:29:30 INFO - PID 3144 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:30 INFO - PID 3144 | 2017-05-30 03:29:30.514 plugin-container[3146:47950] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:29:39 INFO - PID 3144 | RSS: Main: 262111232 03:29:39 INFO - PID 3144 | 03:32:42 INFO - PID 3144 | Cycle 1(1): loaded http://localhost:59582/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59582/tests/dromaeo/cssquery-ext.html) 03:32:43 INFO - PID 3144 | RSS: Main: 267485184 03:32:43 INFO - PID 3144 | 03:35:45 INFO - PID 3144 | Cycle 1(1): loaded http://localhost:59582/tests/dromaeo/cssquery-ext.html (next: http://localhost:59582/tests/dromaeo/cssquery-jquery.html) 03:35:46 INFO - PID 3144 | RSS: Main: 288317440 03:35:46 INFO - PID 3144 | 03:38:48 INFO - PID 3144 | Cycle 1(1): loaded http://localhost:59582/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59582/tests/dromaeo/cssquery-mootools.html) 03:38:48 INFO - PID 3144 | RSS: Main: 288280576 03:38:48 INFO - PID 3144 | 03:41:50 INFO - PID 3144 | Cycle 1(1): loaded http://localhost:59582/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59582/tests/dromaeo/cssquery-prototype.html) 03:41:51 INFO - PID 3144 | RSS: Main: 291897344 03:41:51 INFO - PID 3144 | 03:44:53 INFO - PID 3144 | Cycle 1(1): loaded http://localhost:59582/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59582/tests/dromaeo/cssquery-yui.html) 03:44:54 INFO - PID 3144 | RSS: Main: 291962880 03:44:54 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | Cycle 1(1): loaded http://localhost:59582/tests/dromaeo/cssquery-yui.html (next: http://localhost:59582/tests/dromaeo/cssquery-dojo.html) 03:47:56 INFO - PID 3144 | RSS: Main: 293842944 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | __start_tp_report 03:47:56 INFO - PID 3144 | _x_x_mozilla_page_load 03:47:56 INFO - PID 3144 | _x_x_mozilla_page_load_details 03:47:56 INFO - PID 3144 | |i|pagename|runs| 03:47:56 INFO - PID 3144 | |0;dojo.html;3495.9126021849465;3508.7894726316417;3541.008517615068;3543.8936831895053;3570.4465807799784;6104.125697713681;6132.632042077478;6162.476189523896;6194.845128871781;6239.781607643733;6141.946315431805;6184.165137706408;6200.937990620092;6229.252489701231;6257.687115644211;5362.1420572708375;5471.972640136793;5473.452654734535;5474.233607294972;5486.204500347447;5498.45015498451;5564.33228012639;5588.804391846286;5620.859478513029;5627.634203776762;5359.38367087786;5478.205660179292;5501.614886957915;5525.834224973257;5626.578006649497;4485.573870482299;5422.240886275925;5434.972825135888;5478.260434841285;5504.91742623861;5383.596230282745;5414.864628384283;5443.374011988592;5445.346558412976;5457.099578569531;6119.571629985903;6243.313235544087;6268.279147898004;6271.937280627181;6271.996480563088;5336.4129945705945;6419.165508483914;6455.451286640641;6525.249596296439;6532.608043517404;6672.266050734415;6693.464522838161;6694.531382803206;6703.899441508382;6711.899321510133;6279.277883043508;6318.810435686937;6320.957042088096;6328.145700330442;6332.271788744261;6610.239822420387;6732.124823772976;6828.726850926007;6829.760958366533;6898.448124150056;6294.339094395114;6309.936900631027;6333.873322533524;6408.935910640927;6488.967555162194;6622.10601568782;6641.535092543572;6671.733130674816;6677.265500794909;6680.732770689216;6519.119918810995;6540.607563546202;6552.57408268461;6556.770513032021;6557.868842623217;6370;6444;6495.902561461582;6533.967330163414;6569.638669873203;4807.870150514626;4999.875003124951;5304.177852432879;5309.070912590281;5331.973340133274;4693.530646935279;4714.882127946828;4715.740634265148;4720.95279047212;4746.098241334135;6216.627002379872;6275.403836635513;6291.6854157291955;6315.052742088632;6321.462675672527;6674.933250667528;6730.158730158731;6741.696623651849;6742.9662851685425;6753;6308.653024083627;6318.273398559225;6321.1782468278825;6346.714397852108;6350.714217860207;5764.106563482583;5825.24271844666;5901.970490147522;5915.556333275022;5921.970390148022;6788.626625535741;6811.625360605115;6822.488313376417;6826;6844.315568443116;6703.899441508283;6714.529982901346;6718.093057437183;6733.42765864907;6748.021536877027;6635.303293154149;6790.72837086511;6852.794416167523;6870.3473170048765;6883.139607549057;5833.4749872511675;6259.904516709648;6384.106225128393;6474.773382931575;6524.988626762859;6674.966625166843;6702.865942681022;6713.832154195989;6722.327767223239;6731.663416829237;6277.811665650038;6317.305096439296;6326.936730632819;6381.680915954091;6414.679266036586;5333.866653333697;6372.044193371032;6374.840628984127;6420.229572451336;6426.839329016812;6228.439440450381;6292.1505596743855;6312.084747711464;6325.873482530232;6333.398327158912;6333.271673757393;6369.20384951881;6446.677666116581;6454.806355809334;6462.612243265419;5301.522862942199;6263.154474145932;6281.371862813865;6298.212723409574;6357.237131544244 03:47:56 INFO - PID 3144 | |1;ext.html;757.681773655065;784.1648644193933;845.6363763581658;1233.5004323249082;1303.9804402933964;39559.60440395595;39809.40285895714;40179.59820401795;40239.79880100599;40248.19503609926;40045.39931901024;40380;40494.392584111185;40550.79724601373;40671.79664101675;6763.695633696471;7872.134065252818;8049.235322644339;8478.236958673719;8484.830303393928;8335.291500222458;8339.874901876477;8461.619227134763;8461.915380846174;8462.323014158852;8416.452930559524;8459.280961118313;8472.66109355628;8484.915150848505;8514.063453020162;8294.502329860228;8420.747377578682;8429.283510901581;8458.83082338356;8510.914890851074;6711.261761206263;8175.7138500152805;8187.345012399053;8199.42604017719;8206;37755;37776.866693999225;37966.81016594927;37981;38027.42958855592;3587.497750314932;3631.4734363517405;3635.4546817977252;3637.163452405935;3638.108663377463;26235.688215589144;26932.326691832666;28741.425171496663;30687.386252274828;30924.690753092633;2989.237744375192;3070.7236348728497;3133.6709645487354;3148.889788857379;3155.2900597365638;251201;251473;284204.7369289483;293122;294581;48351.758241209274;48477.75761121172;48615;48638;48782.26826597604;42130;43619;50777.746111269946;50861.49138508642;50888;12504;13653.453861845415;14530.636734081732;14624.976251662494;14740.852591473948;11820.349880756468;11857.644270671894;11868.22856514341;11871.228370156085;11881.5841445549;11913.404329783476;13923.512677056255;14028.29858507091;14050.437982480586;14147.504837330644;5148.9702059588235;5471.206675032098;5934.465898069108;6181.949068658339;6194.628322300677;772.8561728641641;970.1414248390225;1002.013017178079;1033.214756784834;1037.227265687053;23485.41286467818;24322;24572.262832115066;24743.752562474503;24752.752472475044;1089.2103225161695;1094.2012330998416;1123.9606613768478;1124.5614210457927;1134.9148813838995;10085.899141008496;11877.762444751057;12236.776322367692;12440.688982775502;12583.874161258453;330743;332323;333115;333142;333322;42126;54141.729291353295;54376.640583984925;54397.45602544003;54550.454495455335;1345.8990575706862;1468.7136008478244;1510.9848901510845;1513.492979851762;1514.2655811931204;19615.509612259808;20732.48168795732;22698.865056746767;22820.771792281863;24208.16956067438;37625.05937351588;37795.433068503444;37817;37940.810295948344;37964;283470;283814;284022.5798870992;284219;284485;35294.64705352914;35296.647033529334;35922;36160.4575931356;36210.27579448449;43166.56833431751;43936.34095488506;45287;45411.63765087053;45495.545044550454;29220.56169157507;30006.24984375321;30650.846745766128;31295.53056704197;31475;30681.079567613007;30861;30884.536731949487;30896.382072358876;31211.12733235922;36822.63177368192;37262.813685931396;37643.43534846925;37862.810685946395;37930.24139517139;30524.084277471713;30567.694323057374;31035.68964310328;31153;31490.52764208493;22921.083156673547;24226;24281;24477.265682029567;24726.381840454134 03:47:56 INFO - PID 3144 | |2;jquery.html;368.3995088006549;411.0832842760642;424.45669542984973;427.2032659090797;453.9477960034595;19551;19935.30226442075;20719.378418647444;21143.682844757343;21492.032858521365;19708.901455492738;22385;22503.21238756644;22578.322650320515;22636.20773272936;5661.66030038198;5993.940060599403;5994.340622531518;6064.909026364586;6071.028635418334;5928;5964.956132676786;5974.97012514939;6025.307089684677;6046.12331211974;4805.663603547753;5968.045112781956;5986.820395388146;6038.57729958903;6047.697615119248;4705.894114883;5757.107648314518;5962.850928726773;6003.879922401571;6023.3976602339635;5257.605679574009;5292.470752924677;5294.364676238876;5304.124819404832;5309.495597918191;25357.23928282136;25399.873000634878;25508.617370739456;25550.488990220092;25729.970801167932;2105.87364758115;2202.735671719388;2204.1624182810588;2207.381933058746;2208;13459.932700336534;17418.825811741848;17494.950302981862;17611.85522941005;18070.638587228314;2093.3719884034726;2124.7237859078377;2177.7277840269967;2178.2267295110164;2200;134839.32580337036;154949;155600;156067;157018;11701.531938722355;11976.76046479083;12268.386580670931;12358.938205309096;12379.133460657848;15777.526674199793;15804.288807003712;15823.604409889846;15837.604059898595;15869.20653967297;8512.446690965191;8671.306295496344;8684.739457816275;8787.560621969003;8835.955820220857;6736.831579210461;8259.339252859756;8321.625526851307;8371.288440482627;8378.413511054168;6405.391487808651;7489.689304371713;7541.321281084729;7696.846063078707;7697.768357062843;1917.5781328108073;1981.4550998475534;2124.521982553944;2139.176417079436;2143.4855634647574;778.661282342183;859.8538248497771;917.321182325088;927.3832901120679;971.9308760363543;19787.703184452246;20404;20405.795942040688;20436.897815511125;20727.689084663743;952.6094301336419;966.9758256043655;985.9408435493893;993.4337427666305;993.9701808945744;8268.751937441886;10471.633492827867;10534.631287904887;10541.894581054092;10574.894251057545;565357;576805;578645;582651;582687;122919;132733;134288.65711342957;135777;138614;989.3618615992789;1129.5989923577013;1201.327256736213;1230.1819290172114;1232.8828761267666;21131.8943405282;21308.57382852365;21628.35114946554;21893.78106218981;22008.88995555012;46322.768386157855;52885.35573221247;59818.700906495185;59901.10148347691;59978;77419.61290193514;77952;80568.59715701384;81610.59194703988;82166.94582635481;7174.497785154989;8151.429399942184;8455.596143481625;8567.57162141878;8777.122287771428;13006.609801705965;13241.205527668371;13546.86453135456;13551.728965420834;13575.131087639975;11008.009279164913;11295.152863535104;11566.30602163873;11624.593139240085;11690.240134391237;8955.28357731393;10321.277510574188;10572.995565421274;10696.786064278514;10937.289076210021;10880.694316681733;11892;11977.520899163936;11986;11992;7317.487775855639;8486.74539763808;8640.78398040054;8696.869546956674;8755.124487551195;11059.281146725438;11254.099672026054;11280.435978200892;11747.29516229029;11976.880231197576 03:47:56 INFO - PID 3144 | |3;mootools.html;1366.6241783509536;1426.3224968140141;1435.0672063158952;1435.7128574285139;1442.97835532467;9271.76820579485;11769.529218831236;12102.93948530256;12130.757384852308;12172.269663820165;11951.46218420171;11978.580749673763;12125.211861229036;12140.271583704984;12162.878371216286;2539.9111031113866;2580;2589.5597748382725;2590.0675756727564;2592.338953566838;2392.4138586046442;2435.585950388438;2437.0860927152316;2439.316991242455;2439.8414103083333;2089.4880754215237;2515.949681006379;2535.3408113890337;2541.466292078666;2543.923682289534;2474.7401522840023;2483.5653760591913;2487.291122030231;2489.7136829264687;2504.111040580595;2378.203301893868;2484.3913241255827;2493.4789778235263;2497.9883147325363;2504.286278410644;11644.88355116455;11744.060475161967;11753.823692644617;11759.882401175964;11812.173147879652;6492.383223593751;6716.899246511306;6736.191657001143;6744.966275168642;6768.052472653832;4136.669066474675;4345.304751239786;4388.912221755579;4413.470383553994;4416.381706561084;1261.2999785119255;1264.3046324521476;1266.2339284732789;1269.5620011096157;1275.5918106206018;12411.62765117057;14834.480793172295;16537;16603.6679266414;16607.169641517874;4172.374143878443;5209.817656382009;5497.120460726264;5696.971515142398;5710.172025056344;10641.787164256672;11643.76712465746;11863.58477453285;11882.465289062013;11947.880521194676;6367.4906007519285;6461.418472337512;6517.087607734921;6525.2169739631545;6543.378379053982;6086.69566521681;6125.816225513242;6169.81490555284;6247.406496382836;6248.28144763349;4394.736315821061;4430.689851710414;4450.755208463565;4505.36924830524;4518.367428560005;1049.8320268756963;1281.077624110639;1292.5992942187952;1300.8504022037398;1304.5760127958451;793.3256731777942;796.4066770256196;818.6275244763618;827.1645637905758;850.2305413600701;6275.592086514362;6316.810495685137;6502.382273683992;6567.376099270562;6578.934210657928;583.5739909865822;598.1476396135489;599.6881621556767;600.5075837813039;608.5435923057706;3977.244323578511;4037.253108174997;4114.917701645951;4610.631149508032;4626.306054091913;14562.927185364006;15236.923815380853;15326.923365383102;15402.768958465633;15421.768673469907;14759.409623614934;14877.107373557621;15197.088174709554;15239.847601524065;15268.770968435483;8249.175082491704;8507.191816777397;8642.178992995656;8643.870341945001;8648.78378040554;5837.241158649348;6853.520253582202;7120.074390329224;7178.102737157856;7267.382272506896;10233.641822536176;12947.676308091995;12949.676258093623;12956;13198.472061117447;13033.283169425773;13044.282564459047;13208.537701180412;13247.006474514257;13247.867521324664;9322.766930826783;9429.481378524248;9518.95240523793;9529.809403812023;9533.523323833919;8748.781280467785;10698.679039628823;10838.512266947848;10866.130709543375;10867.29362591429;7511.737089201852;8539.615717292612;8911.50986695738;9133.360664753669;9289.442633442015;9002.59488323014;9022.593983270637;9045.638174472948;9082.18260356571;9375.671851485438;8202.958985205036;8272.958635206785;8367.372447066617;8385.035720891932;8402.075771665233;6012.338642749207;6015.308239552332;6099.969500152471;6164.3835616438;6256.155419018374;6050.697465126638;6148.815535533941;6262;6326.335734747785;6397.6481293529305 03:47:56 INFO - PID 3144 | |4;prototype.html;400.13770324949735;403.5782607175501;404.55701007396925;404.8441698949499;407.6901554818338;6249.281332646747;6254.374562543743;6276.748930042804;6347.49220062395;6359.6184228946295;5925.407459254073;6034.909476357847;6068.8482787930325;6108.511319094473;6139;4590.586847183753;4624.190766615829;4633.050224703936;4659.510751371108;4662.883427914296;4542.978374623294;4591.793369298372;4610.262358022717;4655.464621568529;4713.976430117862;4556.2937744649635;4592.311153326994;4603.953960460402;4610.077984403133;4613.769311534426;3979.4826672532677;4558.860284928768;4582.289745089516;4585.541445855388;4635.096156249516;3851.614838516154;4053.148838743867;4055.047063939972;4084.019835239551;4099.344104943195;4699.859004229845;5772.3361813391575;5776.364599894008;5887.7939272125695;6156.199694039791;5314.468553144655;5332.253484512172;5332.813351532716;5337.199420087018;5337.786488540453;5829.4753472187285;6178.536609754242;6566;6634.966825165891;6657.267700552935;4346.56534346566;4352.39066530686;4353.651707863395;4365.192439398689;4371.34429835524;5749.0906183795005;5790.7973220937065;6656.36764507371;6834.6582670866255;6882.724691012353;3918.9027072419767;4057.2899742544937;4247.957520424818;4261.829526818955;4396.692231543761;4285;4495.505494395614;4500.504944456107;4503.500557190725;4514.68397212192;5020.7740651670765;5302.602304827154;5317.867053323621;5344.118220493575;5355.464453554613;5732.254806875163;6400.359964003563;6553.724251977204;6596.934030659728;6635.734570617121;1198.8920997110301;1260.8171815086944;1295.080492850087;1509.6603264265452;1525.80927384077;961.427950369529;998.201438848918;1011.1506334678758;1016.8576399304103;1018.7860549284733;6270.557771712441;6281.403266689657;6306.027202862259;6329.905051424233;6392.744290228339;5996.550258730613;6868.587884726932;6878.58728476293;6891.896621550681;6910.445149841346;5448.727563621803;6012.188354572077;6293.433590976834;6294.929861923392;6320.367963203735;3570.4644303354185;3600.567931848195;3601.2977469393218;3603.4594810778067;3606.5311509503595;6942.514024018371;7053.0125782390505;7053.224145344008;7097.325754053357;7117.71529138839;7042;7066.823329416703;7074.327938845801;7078.575285482888;7120.821979450555;5525.397634685896;5724.2115981671805;6423.74305027803;6701.927691569325;6984.895226571605;5487.396386397413;6833.965830170818;6845.760398386232;6885.793426197222;6918.411934985583;5586.357568879631;6592.077109204619;6609.917875869086;6610.8347291318105;6763.20774994617;6430.421262086435;7024.8595028098125;7039.612821294878;7042.119735033121;7044.295570442915;4255.25533031712;4323.956760432356;4370.300751879811;4400.977995110132;4416.911661766682;6252.5935814171935;6323.826274658703;6600.537962342782;6850.760223392157;6891.414229790524;5427.48438898304;5742.569307301886;5974.820755377345;6334.271558770616;6612.875811112026;6050.850338435683;6567.802965911031;6644;6708.765193218214;6757.391834734897;5454.781808727766;6028.608140470856;6492.383223593751;6493.902591461037;6577.6053436793945;6309.432151106423;6562.50781191403;6667.566608170453;6704.262531121671;6716.529842910949;5652.915206271827;5987.940120598738;6518.706658200297;6529.151210342815;6531.575447595891 03:47:56 INFO - PID 3144 | |5;yui.html;729.4492658043176;812.4353574265886;837.4054421360837;839.2153336630249;849.8951363227802;14651.853481465183;15166.620834479116;16592.668146637076;16695.248713807876;17019;16117.435889743861;17962.37131700391;18017.81982180181;18038.458846234633;18077.4576762697;978.7063880835774;1002.2182697495965;1014.219051330475;1015.0103648942259;1015.6343716262139;993.662154867345;1006.2553710254432;1010.4947526236881;1012.0284526854228;1012.5291739341205;963;998.1615443027855;1036.0261354326947;1039.9064084232418;1045.733337998813;1011.1203253169716;1031.9731866792515;1039.2517387481003;1046.1369370269558;1057.5188289328337;749.1646813802571;761.8476304739019;764.0869161352186;767.0182166826471;772.3589420780738;17228.22472988706;17564.209610567556;17735.556611084696;17800.732989005177;18038.639227215383;17674.469765907044;17804.46586602404;18152.09239538031;18159.45521634353;18192.818071819245;12685.794849489284;14080.15519068859;14393.856061439355;14655.41378344865;14672.486462973744;3450.8447119879665;3477.6870081692514;3483.3207524532727;3518.489818976234;3518.894433167009;148049;149895;151090.24454877657;151160.2441987794;152874;13431.059825812295;13459.596212113653;13469.461221551224;13550.525731599346;13560.389782459813;24646.383840403774;25263;25952.0916767916;25967.350816229744;25995;6786.932130678729;7014.22843487216;7039.260877607778;7925.28672419485;8101.392395570356;2941.2940894185667;3925.685945124383;3928.489296391508;3934.15415685629;3934.783586902691;4127.009517715726;4185.937210941838;4201.2017716633745;4217.493900731932;4225.873223803291;928.8560273157536;931.8741969834122;943.9811203776023;946.3470205558143;946.8532377481501;607.9209403309073;629.7984644913676;639.827246643413;661.7816120680164;680.7889554238202;10775.191860610486;11154.553817847358;12232.938835305766;12308.323042232763;12365.134440589072;568.1988396361111;569.1263909898287;572.093232226925;586.6509426890993;599.1492081244643;5023.874403139951;5516.939629883729;5567.638103523258;5711.657300561979;5785.247917770747;118644;118966;121633;130842;133942;93802;101364;103048;103123.45314820284;104483.4775826116;85876.85307867354;87834.68247976285;87866.56066719626;90242;92166.53916730508;10311.896881031094;11332.320060796379;12204.145709800228;12866.742665146458;12931.935340323238;42520.93697657583;42901.498447554186;43824.34263486114;43874;44202.55797442113;44563.77718111389;51336.486635134665;54162;54280.91438171294;54585.72707136439;16516.17419129063;16605;16839.326426943277;16949.66100677955;16953.321867125178;18109.004004779865;18166.455006349832;18869.622607548048;18876.433706988813;18886.244550217838;15699.607509812346;16147.273372698492;16490.093044882648;16505.83494165043;16560.17199140014;17107.65784684324;17171.656566868343;17205;17226.483205503853;17386.739198911775;13774.311284435538;16480.34078636841;16708.331666733193;16916.915415422845;17022.57443563921;9962.352447090916;11298.378589177346;11973.820392694292;12049.216800907912;12106.455209515769;11217;12606.117571769888;12764.978801696028;12790.168639038433;12908.290044047668 03:47:56 INFO - PID 3144 | __end_tp_report 03:47:56 INFO - PID 3144 | __start_cc_report 03:47:56 INFO - PID 3144 | _x_x_mozilla_cycle_collect,449 03:47:56 INFO - PID 3144 | __end_cc_report 03:47:56 INFO - PID 3144 | __startTimestamp1496141276408__endTimestamp 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | ------- Summary: start ------- 03:47:56 INFO - PID 3144 | Number of tests: 6 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | [#0] dojo.html Cycles:165 Average:6097.94 Median:6318.81 stddev:713.17 (11.3%) stddev-sans-first:685.52 03:47:56 INFO - PID 3144 | Values: 3495.9 3508.8 3541.0 3543.9 3570.4 6104.1 6132.6 6162.5 6194.8 6239.8 6141.9 6184.2 6200.9 6229.3 6257.7 5362.1 5472.0 5473.5 5474.2 5486.2 5498.5 5564.3 5588.8 5620.9 5627.6 5359.4 5478.2 5501.6 5525.8 5626.6 4485.6 5422.2 5435.0 5478.3 5504.9 5383.6 5414.9 5443.4 5445.3 5457.1 6119.6 6243.3 6268.3 6271.9 6272.0 5336.4 6419.2 6455.5 6525.2 6532.6 6672.3 6693.5 6694.5 6703.9 6711.9 6279.3 6318.8 6321.0 6328.1 6332.3 6610.2 6732.1 6828.7 6829.8 6898.4 6294.3 6309.9 6333.9 6408.9 6489.0 6622.1 6641.5 6671.7 6677.3 6680.7 6519.1 6540.6 6552.6 6556.8 6557.9 6370.0 6444.0 6495.9 6534.0 6569.6 4807.9 4999.9 5304.2 5309.1 5332.0 4693.5 4714.9 4715.7 4721.0 4746.1 6216.6 6275.4 6291.7 6315.1 6321.5 6674.9 6730.2 6741.7 6743.0 6753.0 6308.7 6318.3 6321.2 6346.7 6350.7 5764.1 5825.2 5902.0 5915.6 5922.0 6788.6 6811.6 6822.5 6826.0 6844.3 6703.9 6714.5 6718.1 6733.4 6748.0 6635.3 6790.7 6852.8 6870.3 6883.1 5833.5 6259.9 6384.1 6474.8 6525.0 6675.0 6702.9 6713.8 6722.3 6731.7 6277.8 6317.3 6326.9 6381.7 6414.7 5333.9 6372.0 6374.8 6420.2 6426.8 6228.4 6292.2 6312.1 6325.9 6333.4 6333.3 6369.2 6446.7 6454.8 6462.6 5301.5 6263.2 6281.4 6298.2 6357.2 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | [#1] ext.html Cycles:180 Average:44855.95 Median:35295.65 stddev:78269.77 (221.8%) stddev-sans-first:78419.30 03:47:56 INFO - PID 3144 | Values: 757.7 784.2 845.6 1233.5 1304.0 39559.6 39809.4 40179.6 40239.8 40248.2 40045.4 40380.0 40494.4 40550.8 40671.8 6763.7 7872.1 8049.2 8478.2 8484.8 8335.3 8339.9 8461.6 8461.9 8462.3 8416.5 8459.3 8472.7 8484.9 8514.1 8294.5 8420.7 8429.3 8458.8 8510.9 6711.3 8175.7 8187.3 8199.4 8206.0 37755.0 37776.9 37966.8 37981.0 38027.4 3587.5 3631.5 3635.5 3637.2 3638.1 26235.7 26932.3 28741.4 30687.4 30924.7 2989.2 3070.7 3133.7 3148.9 3155.3 251201.0 251473.0 284204.7 293122.0 294581.0 48351.8 48477.8 48615.0 48638.0 48782.3 42130.0 43619.0 50777.7 50861.5 50888.0 12504.0 13653.5 14530.6 14625.0 14740.9 11820.3 11857.6 11868.2 11871.2 11881.6 11913.4 13923.5 14028.3 14050.4 14147.5 5149.0 5471.2 5934.5 6181.9 6194.6 772.9 970.1 1002.0 1033.2 1037.2 23485.4 24322.0 24572.3 24743.8 24752.8 1089.2 1094.2 1124.0 1124.6 1134.9 10085.9 11877.8 12236.8 12440.7 12583.9 330743.0 332323.0 333115.0 333142.0 333322.0 42126.0 54141.7 54376.6 54397.5 54550.5 1345.9 1468.7 1511.0 1513.5 1514.3 19615.5 20732.5 22698.9 22820.8 24208.2 37625.1 37795.4 37817.0 37940.8 37964.0 283470.0 283814.0 284022.6 284219.0 284485.0 35294.6 35296.6 35922.0 36160.5 36210.3 43166.6 43936.3 45287.0 45411.6 45495.5 29220.6 30006.2 30650.8 31295.5 31475.0 30681.1 30861.0 30884.5 30896.4 31211.1 36822.6 37262.8 37643.4 37862.8 37930.2 30524.1 30567.7 31035.7 31153.0 31490.5 22921.1 24226.0 24281.0 24477.3 24726.4 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | [#2] jquery.html Cycles:180 Average:36108.38 Median:22607.27 stddev:97600.17 (431.7%) stddev-sans-first:97837.07 03:47:56 INFO - PID 3144 | Values: 368.4 411.1 424.5 427.2 453.9 19551.0 19935.3 20719.4 21143.7 21492.0 19708.9 22385.0 22503.2 22578.3 22636.2 5661.7 5993.9 5994.3 6064.9 6071.0 5928.0 5965.0 5975.0 6025.3 6046.1 4805.7 5968.0 5986.8 6038.6 6047.7 4705.9 5757.1 5962.9 6003.9 6023.4 5257.6 5292.5 5294.4 5304.1 5309.5 25357.2 25399.9 25508.6 25550.5 25730.0 2105.9 2202.7 2204.2 2207.4 2208.0 13459.9 17418.8 17495.0 17611.9 18070.6 2093.4 2124.7 2177.7 2178.2 2200.0 134839.3 154949.0 155600.0 156067.0 157018.0 11701.5 11976.8 12268.4 12358.9 12379.1 15777.5 15804.3 15823.6 15837.6 15869.2 8512.4 8671.3 8684.7 8787.6 8836.0 6736.8 8259.3 8321.6 8371.3 8378.4 6405.4 7489.7 7541.3 7696.8 7697.8 1917.6 1981.5 2124.5 2139.2 2143.5 778.7 859.9 917.3 927.4 971.9 19787.7 20404.0 20405.8 20436.9 20727.7 952.6 967.0 985.9 993.4 994.0 8268.8 10471.6 10534.6 10541.9 10574.9 565357.0 576805.0 578645.0 582651.0 582687.0 122919.0 132733.0 134288.7 135777.0 138614.0 989.4 1129.6 1201.3 1230.2 1232.9 21131.9 21308.6 21628.4 21893.8 22008.9 46322.8 52885.4 59818.7 59901.1 59978.0 77419.6 77952.0 80568.6 81610.6 82166.9 7174.5 8151.4 8455.6 8567.6 8777.1 13006.6 13241.2 13546.9 13551.7 13575.1 11008.0 11295.2 11566.3 11624.6 11690.2 8955.3 10321.3 10573.0 10696.8 10937.3 10880.7 11892.0 11977.5 11986.0 11992.0 7317.5 8486.7 8640.8 8696.9 8755.1 11059.3 11254.1 11280.4 11747.3 11976.9 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | [#3] mootools.html Cycles:180 Average:7049.38 Median:4125.79 stddev:4426.60 (107.3%) stddev-sans-first:4418.42 03:47:56 INFO - PID 3144 | Values: 1366.6 1426.3 1435.1 1435.7 1443.0 9271.8 11769.5 12102.9 12130.8 12172.3 11951.5 11978.6 12125.2 12140.3 12162.9 2539.9 2580.0 2589.6 2590.1 2592.3 2392.4 2435.6 2437.1 2439.3 2439.8 2089.5 2515.9 2535.3 2541.5 2543.9 2474.7 2483.6 2487.3 2489.7 2504.1 2378.2 2484.4 2493.5 2498.0 2504.3 11644.9 11744.1 11753.8 11759.9 11812.2 6492.4 6716.9 6736.2 6745.0 6768.1 4136.7 4345.3 4388.9 4413.5 4416.4 1261.3 1264.3 1266.2 1269.6 1275.6 12411.6 14834.5 16537.0 16603.7 16607.2 4172.4 5209.8 5497.1 5697.0 5710.2 10641.8 11643.8 11863.6 11882.5 11947.9 6367.5 6461.4 6517.1 6525.2 6543.4 6086.7 6125.8 6169.8 6247.4 6248.3 4394.7 4430.7 4450.8 4505.4 4518.4 1049.8 1281.1 1292.6 1300.9 1304.6 793.3 796.4 818.6 827.2 850.2 6275.6 6316.8 6502.4 6567.4 6578.9 583.6 598.1 599.7 600.5 608.5 3977.2 4037.3 4114.9 4610.6 4626.3 14562.9 15236.9 15326.9 15402.8 15421.8 14759.4 14877.1 15197.1 15239.8 15268.8 8249.2 8507.2 8642.2 8643.9 8648.8 5837.2 6853.5 7120.1 7178.1 7267.4 10233.6 12947.7 12949.7 12956.0 13198.5 13033.3 13044.3 13208.5 13247.0 13247.9 9322.8 9429.5 9519.0 9529.8 9533.5 8748.8 10698.7 10838.5 10866.1 10867.3 7511.7 8539.6 8911.5 9133.4 9289.4 9002.6 9022.6 9045.6 9082.2 9375.7 8203.0 8273.0 8367.4 8385.0 8402.1 6012.3 6015.3 6100.0 6164.4 6256.2 6050.7 6148.8 6262.0 6326.3 6397.6 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | [#4] prototype.html Cycles:180 Average:5317.89 Median:5950.11 stddev:1668.16 (28.0%) stddev-sans-first:1631.49 03:47:56 INFO - PID 3144 | Values: 400.1 403.6 404.6 404.8 407.7 6249.3 6254.4 6276.7 6347.5 6359.6 5925.4 6034.9 6068.8 6108.5 6139.0 4590.6 4624.2 4633.1 4659.5 4662.9 4543.0 4591.8 4610.3 4655.5 4714.0 4556.3 4592.3 4604.0 4610.1 4613.8 3979.5 4558.9 4582.3 4585.5 4635.1 3851.6 4053.1 4055.0 4084.0 4099.3 4699.9 5772.3 5776.4 5887.8 6156.2 5314.5 5332.3 5332.8 5337.2 5337.8 5829.5 6178.5 6566.0 6635.0 6657.3 4346.6 4352.4 4353.7 4365.2 4371.3 5749.1 5790.8 6656.4 6834.7 6882.7 3918.9 4057.3 4248.0 4261.8 4396.7 4285.0 4495.5 4500.5 4503.5 4514.7 5020.8 5302.6 5317.9 5344.1 5355.5 5732.3 6400.4 6553.7 6596.9 6635.7 1198.9 1260.8 1295.1 1509.7 1525.8 961.4 998.2 1011.2 1016.9 1018.8 6270.6 6281.4 6306.0 6329.9 6392.7 5996.6 6868.6 6878.6 6891.9 6910.4 5448.7 6012.2 6293.4 6294.9 6320.4 3570.5 3600.6 3601.3 3603.5 3606.5 6942.5 7053.0 7053.2 7097.3 7117.7 7042.0 7066.8 7074.3 7078.6 7120.8 5525.4 5724.2 6423.7 6701.9 6984.9 5487.4 6834.0 6845.8 6885.8 6918.4 5586.4 6592.1 6609.9 6610.8 6763.2 6430.4 7024.9 7039.6 7042.1 7044.3 4255.3 4324.0 4370.3 4401.0 4416.9 6252.6 6323.8 6600.5 6850.8 6891.4 5427.5 5742.6 5974.8 6334.3 6612.9 6050.9 6567.8 6644.0 6708.8 6757.4 5454.8 6028.6 6492.4 6493.9 6577.6 6309.4 6562.5 6667.6 6704.3 6716.5 5652.9 5987.9 6518.7 6529.2 6531.6 03:47:56 INFO - PID 3144 | 03:47:56 INFO - PID 3144 | [#5] yui.html Cycles:180 Average:23552.95 Median:17883.42 stddev:35633.31 (199.3%) stddev-sans-first:35692.06 03:47:56 INFO - PID 3144 | Values: 729.4 812.4 837.4 839.2 849.9 14651.9 15166.6 16592.7 16695.2 17019.0 16117.4 17962.4 18017.8 18038.5 18077.5 978.7 1002.2 1014.2 1015.0 1015.6 993.7 1006.3 1010.5 1012.0 1012.5 963.0 998.2 1036.0 1039.9 1045.7 1011.1 1032.0 1039.3 1046.1 1057.5 749.2 761.8 764.1 767.0 772.4 17228.2 17564.2 17735.6 17800.7 18038.6 17674.5 17804.5 18152.1 18159.5 18192.8 12685.8 14080.2 14393.9 14655.4 14672.5 3450.8 3477.7 3483.3 3518.5 3518.9 148049.0 149895.0 151090.2 151160.2 152874.0 13431.1 13459.6 13469.5 13550.5 13560.4 24646.4 25263.0 25952.1 25967.4 25995.0 6786.9 7014.2 7039.3 7925.3 8101.4 2941.3 3925.7 3928.5 3934.2 3934.8 4127.0 4185.9 4201.2 4217.5 4225.9 928.9 931.9 944.0 946.3 946.9 607.9 629.8 639.8 661.8 680.8 10775.2 11154.6 12232.9 12308.3 12365.1 568.2 569.1 572.1 586.7 599.1 5023.9 5516.9 5567.6 5711.7 5785.2 118644.0 118966.0 121633.0 130842.0 133942.0 93802.0 101364.0 103048.0 103123.5 104483.5 85876.9 87834.7 87866.6 90242.0 92166.5 10311.9 11332.3 12204.1 12866.7 12931.9 42520.9 42901.5 43824.3 43874.0 44202.6 44563.8 51336.5 54162.0 54280.9 54585.7 16516.2 16605.0 16839.3 16949.7 16953.3 18109.0 18166.5 18869.6 18876.4 18886.2 15699.6 16147.3 16490.1 16505.8 16560.2 17107.7 17171.7 17205.0 17226.5 17386.7 13774.3 16480.3 16708.3 16916.9 17022.6 9962.4 11298.4 11973.8 12049.2 12106.5 11217.0 12606.1 12765.0 12790.2 12908.3 03:47:56 INFO - PID 3144 | -------- Summary: end -------- 03:47:56 INFO - PID 3144 | 03:47:56 INFO - TEST-INFO | 3144: exit 0 03:47:57 INFO - TEST-OK | dromaeo_css | took 1143266ms 03:47:57 INFO - TEST-START | kraken 03:47:57 INFO - operating with platform_type : mac_ 03:47:57 INFO - Initialising browser for kraken test... 03:47:57 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:59582/getInfo.html -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpRHKh0u/profile 03:47:57 INFO - TEST-INFO | started process 3173 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:59582/getInfo.html -foreground) 03:48:30 INFO - TEST-INFO | 3173: killed by SIGTERM 03:48:30 INFO - Browser initialized. 03:48:30 INFO - Running cycle 1/1 for kraken test... 03:48:30 INFO - TEST-INFO | started process 3177 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpRHKh0u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:48:33 INFO - PID 3177 | 2017-05-30 03:48:33.432 plugin-container[3178:50373] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 03:48:33 INFO - PID 3177 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:33 INFO - PID 3177 | 2017-05-30 03:48:33.432 plugin-container[3178:50373] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 03:48:33 INFO - PID 3177 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:33 INFO - PID 3177 | 2017-05-30 03:48:33.432 plugin-container[3178:50373] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:48:33 INFO - PID 3177 | 2017-05-30 03:48:33.853 plugin-container[3179:50418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 03:48:33 INFO - PID 3177 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:33 INFO - PID 3177 | 2017-05-30 03:48:33.853 plugin-container[3179:50418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9437, name = 'com.apple.CFPasteboardClient' 03:48:33 INFO - PID 3177 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:33 INFO - PID 3177 | 2017-05-30 03:48:33.853 plugin-container[3179:50418] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:48:43 INFO - PID 3177 | RSS: Main: 262864896 03:48:43 INFO - PID 3177 | 03:48:45 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59582/tests/kraken/driver.html?testName=audio-beat-detection) 03:48:46 INFO - PID 3177 | RSS: Main: 272105472 03:48:46 INFO - PID 3177 | 03:48:49 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59582/tests/kraken/driver.html?testName=audio-dft) 03:48:50 INFO - PID 3177 | RSS: Main: 280203264 03:48:50 INFO - PID 3177 | 03:48:53 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59582/tests/kraken/driver.html?testName=audio-fft) 03:48:54 INFO - PID 3177 | RSS: Main: 281964544 03:48:54 INFO - PID 3177 | 03:48:56 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59582/tests/kraken/driver.html?testName=audio-oscillator) 03:48:56 INFO - PID 3177 | RSS: Main: 284528640 03:48:56 INFO - PID 3177 | 03:48:58 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59582/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:48:59 INFO - PID 3177 | RSS: Main: 284782592 03:48:59 INFO - PID 3177 | 03:49:02 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59582/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:02 INFO - PID 3177 | RSS: Main: 289361920 03:49:02 INFO - PID 3177 | 03:49:05 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59582/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:06 INFO - PID 3177 | RSS: Main: 292360192 03:49:06 INFO - PID 3177 | 03:49:09 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59582/tests/kraken/driver.html?testName=json-parse-financial) 03:49:09 INFO - PID 3177 | RSS: Main: 291422208 03:49:09 INFO - PID 3177 | 03:49:11 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59582/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:12 INFO - PID 3177 | RSS: Main: 291106816 03:49:12 INFO - PID 3177 | 03:49:13 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:14 INFO - PID 3177 | RSS: Main: 290971648 03:49:14 INFO - PID 3177 | 03:49:16 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:17 INFO - PID 3177 | RSS: Main: 290807808 03:49:17 INFO - PID 3177 | 03:49:20 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:20 INFO - PID 3177 | RSS: Main: 290299904 03:49:20 INFO - PID 3177 | 03:49:24 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:24 INFO - PID 3177 | RSS: Main: 290541568 03:49:24 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | Cycle 1(1): loaded http://localhost:59582/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59582/tests/kraken/driver.html?testName=ai-astar) 03:49:26 INFO - PID 3177 | RSS: Main: 292421632 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | __start_tp_report 03:49:26 INFO - PID 3177 | _x_x_mozilla_page_load 03:49:26 INFO - PID 3177 | _x_x_mozilla_page_load_details 03:49:26 INFO - PID 3177 | |i|pagename|runs| 03:49:26 INFO - PID 3177 | |0;ai-astar;101;115;112;111;115;114;114;114;116;115 03:49:26 INFO - PID 3177 | |1;audio-beat-detection;177;147;111;153;112;147;114;112;153;144 03:49:26 INFO - PID 3177 | |2;audio-dft;198;201;206;213;214;250;258;210;203;196 03:49:26 INFO - PID 3177 | |3;audio-fft;75;62;61;85;59;59;60;58;78;56 03:49:26 INFO - PID 3177 | |4;audio-oscillator;91;69;84;70;68;73;87;68;73;92 03:49:26 INFO - PID 3177 | |5;imaging-gaussian-blur;88;88;88;92;88;88;88;91;88;89 03:49:26 INFO - PID 3177 | |6;imaging-darkroom;96;96;97;96;104;100;97;95;134;96 03:49:26 INFO - PID 3177 | |7;imaging-desaturate;78;148;139;96;92;92;97;90;92;93 03:49:26 INFO - PID 3177 | |8;json-parse-financial;51;50;50;52;54;50;46;46;68;70 03:49:26 INFO - PID 3177 | |9;json-stringify-tinderbox;56;57;59;65;63;61;56;55;59;77 03:49:26 INFO - PID 3177 | |10;stanford-crypto-aes;90;87;85;86;89;86;89;89;131;86 03:49:26 INFO - PID 3177 | |11;stanford-crypto-ccm;140;141;141;139;150;136;141;137;162;131 03:49:26 INFO - PID 3177 | |12;stanford-crypto-pbkdf2;191;185;187;190;188;191;191;203;173;243 03:49:26 INFO - PID 3177 | |13;stanford-crypto-sha256-iterative;67;71;68;69;69;64;59;62;100;94 03:49:26 INFO - PID 3177 | __end_tp_report 03:49:26 INFO - PID 3177 | __start_cc_report 03:49:26 INFO - PID 3177 | _x_x_mozilla_cycle_collect,1184 03:49:26 INFO - PID 3177 | __end_cc_report 03:49:26 INFO - PID 3177 | __startTimestamp1496141366735__endTimestamp 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | ------- Summary: start ------- 03:49:26 INFO - PID 3177 | Number of tests: 14 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.50 stddev:4.37 (3.8%) stddev-sans-first:1.58 03:49:26 INFO - PID 3177 | Values: 101.0 115.0 112.0 111.0 115.0 114.0 114.0 114.0 116.0 115.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:147.00 stddev:23.13 (15.7%) stddev-sans-first:19.49 03:49:26 INFO - PID 3177 | Values: 177.0 147.0 111.0 153.0 112.0 147.0 114.0 112.0 153.0 144.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#2] audio-dft Cycles:10 Average:214.90 Median:211.50 stddev:21.54 (10.2%) stddev-sans-first:21.96 03:49:26 INFO - PID 3177 | Values: 198.0 201.0 206.0 213.0 214.0 250.0 258.0 210.0 203.0 196.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#3] audio-fft Cycles:10 Average:65.30 Median:61.50 stddev:10.11 (16.4%) stddev-sans-first:10.10 03:49:26 INFO - PID 3177 | Values: 75.0 62.0 61.0 85.0 59.0 59.0 60.0 58.0 78.0 56.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.50 stddev:9.86 (12.6%) stddev-sans-first:9.17 03:49:26 INFO - PID 3177 | Values: 91.0 69.0 84.0 70.0 68.0 73.0 87.0 68.0 73.0 92.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:88.00 stddev:1.48 (1.7%) stddev-sans-first:1.54 03:49:26 INFO - PID 3177 | Values: 88.0 88.0 88.0 92.0 88.0 88.0 88.0 91.0 88.0 89.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:96.00 stddev:11.86 (12.4%) stddev-sans-first:12.44 03:49:26 INFO - PID 3177 | Values: 96.0 96.0 97.0 96.0 104.0 100.0 97.0 95.0 134.0 96.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:92.00 stddev:22.72 (24.7%) stddev-sans-first:22.42 03:49:26 INFO - PID 3177 | Values: 78.0 148.0 139.0 96.0 92.0 92.0 97.0 90.0 92.0 93.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:51.50 stddev:8.43 (16.4%) stddev-sans-first:8.89 03:49:26 INFO - PID 3177 | Values: 51.0 50.0 50.0 52.0 54.0 50.0 46.0 46.0 68.0 70.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:60.00 stddev:6.55 (10.9%) stddev-sans-first:6.71 03:49:26 INFO - PID 3177 | Values: 56.0 57.0 59.0 65.0 63.0 61.0 56.0 55.0 59.0 77.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:88.00 stddev:13.88 (15.8%) stddev-sans-first:14.71 03:49:26 INFO - PID 3177 | Values: 90.0 87.0 85.0 86.0 89.0 86.0 89.0 89.0 131.0 86.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:141.00 stddev:8.57 (6.1%) stddev-sans-first:9.07 03:49:26 INFO - PID 3177 | Values: 140.0 141.0 141.0 139.0 150.0 136.0 141.0 137.0 162.0 131.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.20 Median:191.00 stddev:18.65 (9.8%) stddev-sans-first:19.75 03:49:26 INFO - PID 3177 | Values: 191.0 185.0 187.0 190.0 188.0 191.0 191.0 203.0 173.0 243.0 03:49:26 INFO - PID 3177 | 03:49:26 INFO - PID 3177 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:68.50 stddev:13.58 (19.8%) stddev-sans-first:14.27 03:49:26 INFO - PID 3177 | Values: 67.0 71.0 68.0 69.0 69.0 64.0 59.0 62.0 100.0 94.0 03:49:26 INFO - PID 3177 | -------- Summary: end -------- 03:49:26 INFO - PID 3177 | 03:49:27 INFO - TEST-INFO | 3177: exit 0 03:49:27 INFO - TEST-OK | kraken | took 90530ms 03:49:27 INFO - SUITE-END | took 1233s 03:49:27 INFO - Completed test suite (00:20:34) 03:49:27 INFO - javascript benchmark 03:49:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7826.178396823107, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3495.9126021849465, 3508.7894726316417, 3541.008517615068, 3543.8936831895053, 3570.4465807799784, 6104.125697713681, 6132.632042077478, 6162.476189523896, 6194.845128871781, 6239.781607643733, 6141.946315431805, 6184.165137706408, 6200.937990620092, 6229.252489701231, 6257.687115644211, 5362.1420572708375, 5471.972640136793, 5473.452654734535, 5474.233607294972, 5486.204500347447, 5498.45015498451, 5564.33228012639, 5588.804391846286, 5620.859478513029, 5627.634203776762, 5359.38367087786, 5478.205660179292, 5501.614886957915, 5525.834224973257, 5626.578006649497, 4485.573870482299, 5422.240886275925, 5434.972825135888, 5478.260434841285, 5504.91742623861, 5383.596230282745, 5414.864628384283, 5443.374011988592, 5445.346558412976, 5457.099578569531, 6119.571629985903, 6243.313235544087, 6268.279147898004, 6271.937280627181, 6271.996480563088, 5336.4129945705945, 6419.165508483914, 6455.451286640641, 6525.249596296439, 6532.608043517404, 6672.266050734415, 6693.464522838161, 6694.531382803206, 6703.899441508382, 6711.899321510133, 6279.277883043508, 6318.810435686937, 6320.957042088096, 6328.145700330442, 6332.271788744261, 6610.239822420387, 6732.124823772976, 6828.726850926007, 6829.760958366533, 6898.448124150056, 6294.339094395114, 6309.936900631027, 6333.873322533524, 6408.935910640927, 6488.967555162194, 6622.10601568782, 6641.535092543572, 6671.733130674816, 6677.265500794909, 6680.732770689216, 6519.119918810995, 6540.607563546202, 6552.57408268461, 6556.770513032021, 6557.868842623217, 6370.0, 6444.0, 6495.902561461582, 6533.967330163414, 6569.638669873203, 4807.870150514626, 4999.875003124951, 5304.177852432879, 5309.070912590281, 5331.973340133274, 4693.530646935279, 4714.882127946828, 4715.740634265148, 4720.95279047212, 4746.098241334135, 6216.627002379872, 6275.403836635513, 6291.6854157291955, 6315.052742088632, 6321.462675672527, 6674.933250667528, 6730.158730158731, 6741.696623651849, 6742.9662851685425, 6753.0, 6308.653024083627, 6318.273398559225, 6321.1782468278825, 6346.714397852108, 6350.714217860207, 5764.106563482583, 5825.24271844666, 5901.970490147522, 5915.556333275022, 5921.970390148022, 6788.626625535741, 6811.625360605115, 6822.488313376417, 6826.0, 6844.315568443116, 6703.899441508283, 6714.529982901346, 6718.093057437183, 6733.42765864907, 6748.021536877027, 6635.303293154149, 6790.72837086511, 6852.794416167523, 6870.3473170048765, 6883.139607549057, 5833.4749872511675, 6259.904516709648, 6384.106225128393, 6474.773382931575, 6524.988626762859, 6674.966625166843, 6702.865942681022, 6713.832154195989, 6722.327767223239, 6731.663416829237, 6277.811665650038, 6317.305096439296, 6326.936730632819, 6381.680915954091, 6414.679266036586, 5333.866653333697, 6372.044193371032, 6374.840628984127, 6420.229572451336, 6426.839329016812, 6228.439440450381, 6292.1505596743855, 6312.084747711464, 6325.873482530232, 6333.398327158912, 6333.271673757393, 6369.20384951881, 6446.677666116581, 6454.806355809334, 6462.612243265419, 5301.522862942199, 6263.154474145932, 6281.371862813865, 6298.212723409574, 6357.237131544244], "value": 6051.107965724406, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.681773655065, 784.1648644193933, 845.6363763581658, 1233.5004323249082, 1303.9804402933964, 39559.60440395595, 39809.40285895714, 40179.59820401795, 40239.79880100599, 40248.19503609926, 40045.39931901024, 40380.0, 40494.392584111185, 40550.79724601373, 40671.79664101675, 6763.695633696471, 7872.134065252818, 8049.235322644339, 8478.236958673719, 8484.830303393928, 8335.291500222458, 8339.874901876477, 8461.619227134763, 8461.915380846174, 8462.323014158852, 8416.452930559524, 8459.280961118313, 8472.66109355628, 8484.915150848505, 8514.063453020162, 8294.502329860228, 8420.747377578682, 8429.283510901581, 8458.83082338356, 8510.914890851074, 6711.261761206263, 8175.7138500152805, 8187.345012399053, 8199.42604017719, 8206.0, 37755.0, 37776.866693999225, 37966.81016594927, 37981.0, 38027.42958855592, 3587.497750314932, 3631.4734363517405, 3635.4546817977252, 3637.163452405935, 3638.108663377463, 26235.688215589144, 26932.326691832666, 28741.425171496663, 30687.386252274828, 30924.690753092633, 2989.237744375192, 3070.7236348728497, 3133.6709645487354, 3148.889788857379, 3155.2900597365638, 251201.0, 251473.0, 284204.7369289483, 293122.0, 294581.0, 48351.758241209274, 48477.75761121172, 48615.0, 48638.0, 48782.26826597604, 42130.0, 43619.0, 50777.746111269946, 50861.49138508642, 50888.0, 12504.0, 13653.453861845415, 14530.636734081732, 14624.976251662494, 14740.852591473948, 11820.349880756468, 11857.644270671894, 11868.22856514341, 11871.228370156085, 11881.5841445549, 11913.404329783476, 13923.512677056255, 14028.29858507091, 14050.437982480586, 14147.504837330644, 5148.9702059588235, 5471.206675032098, 5934.465898069108, 6181.949068658339, 6194.628322300677, 772.8561728641641, 970.1414248390225, 1002.013017178079, 1033.214756784834, 1037.227265687053, 23485.41286467818, 24322.0, 24572.262832115066, 24743.752562474503, 24752.752472475044, 1089.2103225161695, 1094.2012330998416, 1123.9606613768478, 1124.5614210457927, 1134.9148813838995, 10085.899141008496, 11877.762444751057, 12236.776322367692, 12440.688982775502, 12583.874161258453, 330743.0, 332323.0, 333115.0, 333142.0, 333322.0, 42126.0, 54141.729291353295, 54376.640583984925, 54397.45602544003, 54550.454495455335, 1345.8990575706862, 1468.7136008478244, 1510.9848901510845, 1513.492979851762, 1514.2655811931204, 19615.509612259808, 20732.48168795732, 22698.865056746767, 22820.771792281863, 24208.16956067438, 37625.05937351588, 37795.433068503444, 37817.0, 37940.810295948344, 37964.0, 283470.0, 283814.0, 284022.5798870992, 284219.0, 284485.0, 35294.64705352914, 35296.647033529334, 35922.0, 36160.4575931356, 36210.27579448449, 43166.56833431751, 43936.34095488506, 45287.0, 45411.63765087053, 45495.545044550454, 29220.56169157507, 30006.24984375321, 30650.846745766128, 31295.53056704197, 31475.0, 30681.079567613007, 30861.0, 30884.536731949487, 30896.382072358876, 31211.12733235922, 36822.63177368192, 37262.813685931396, 37643.43534846925, 37862.810685946395, 37930.24139517139, 30524.084277471713, 30567.694323057374, 31035.68964310328, 31153.0, 31490.52764208493, 22921.083156673547, 24226.0, 24281.0, 24477.265682029567, 24726.381840454134], "value": 17424.297973030098, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.3995088006549, 411.0832842760642, 424.45669542984973, 427.2032659090797, 453.9477960034595, 19551.0, 19935.30226442075, 20719.378418647444, 21143.682844757343, 21492.032858521365, 19708.901455492738, 22385.0, 22503.21238756644, 22578.322650320515, 22636.20773272936, 5661.66030038198, 5993.940060599403, 5994.340622531518, 6064.909026364586, 6071.028635418334, 5928.0, 5964.956132676786, 5974.97012514939, 6025.307089684677, 6046.12331211974, 4805.663603547753, 5968.045112781956, 5986.820395388146, 6038.57729958903, 6047.697615119248, 4705.894114883, 5757.107648314518, 5962.850928726773, 6003.879922401571, 6023.3976602339635, 5257.605679574009, 5292.470752924677, 5294.364676238876, 5304.124819404832, 5309.495597918191, 25357.23928282136, 25399.873000634878, 25508.617370739456, 25550.488990220092, 25729.970801167932, 2105.87364758115, 2202.735671719388, 2204.1624182810588, 2207.381933058746, 2208.0, 13459.932700336534, 17418.825811741848, 17494.950302981862, 17611.85522941005, 18070.638587228314, 2093.3719884034726, 2124.7237859078377, 2177.7277840269967, 2178.2267295110164, 2200.0, 134839.32580337036, 154949.0, 155600.0, 156067.0, 157018.0, 11701.531938722355, 11976.76046479083, 12268.386580670931, 12358.938205309096, 12379.133460657848, 15777.526674199793, 15804.288807003712, 15823.604409889846, 15837.604059898595, 15869.20653967297, 8512.446690965191, 8671.306295496344, 8684.739457816275, 8787.560621969003, 8835.955820220857, 6736.831579210461, 8259.339252859756, 8321.625526851307, 8371.288440482627, 8378.413511054168, 6405.391487808651, 7489.689304371713, 7541.321281084729, 7696.846063078707, 7697.768357062843, 1917.5781328108073, 1981.4550998475534, 2124.521982553944, 2139.176417079436, 2143.4855634647574, 778.661282342183, 859.8538248497771, 917.321182325088, 927.3832901120679, 971.9308760363543, 19787.703184452246, 20404.0, 20405.795942040688, 20436.897815511125, 20727.689084663743, 952.6094301336419, 966.9758256043655, 985.9408435493893, 993.4337427666305, 993.9701808945744, 8268.751937441886, 10471.633492827867, 10534.631287904887, 10541.894581054092, 10574.894251057545, 565357.0, 576805.0, 578645.0, 582651.0, 582687.0, 122919.0, 132733.0, 134288.65711342957, 135777.0, 138614.0, 989.3618615992789, 1129.5989923577013, 1201.327256736213, 1230.1819290172114, 1232.8828761267666, 21131.8943405282, 21308.57382852365, 21628.35114946554, 21893.78106218981, 22008.88995555012, 46322.768386157855, 52885.35573221247, 59818.700906495185, 59901.10148347691, 59978.0, 77419.61290193514, 77952.0, 80568.59715701384, 81610.59194703988, 82166.94582635481, 7174.497785154989, 8151.429399942184, 8455.596143481625, 8567.57162141878, 8777.122287771428, 13006.609801705965, 13241.205527668371, 13546.86453135456, 13551.728965420834, 13575.131087639975, 11008.009279164913, 11295.152863535104, 11566.30602163873, 11624.593139240085, 11690.240134391237, 8955.28357731393, 10321.277510574188, 10572.995565421274, 10696.786064278514, 10937.289076210021, 10880.694316681733, 11892.0, 11977.520899163936, 11986.0, 11992.0, 7317.487775855639, 8486.74539763808, 8640.78398040054, 8696.869546956674, 8755.124487551195, 11059.281146725438, 11254.099672026054, 11280.435978200892, 11747.29516229029, 11976.880231197576], "value": 10137.319933190942, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.6241783509536, 1426.3224968140141, 1435.0672063158952, 1435.7128574285139, 1442.97835532467, 9271.76820579485, 11769.529218831236, 12102.93948530256, 12130.757384852308, 12172.269663820165, 11951.46218420171, 11978.580749673763, 12125.211861229036, 12140.271583704984, 12162.878371216286, 2539.9111031113866, 2580.0, 2589.5597748382725, 2590.0675756727564, 2592.338953566838, 2392.4138586046442, 2435.585950388438, 2437.0860927152316, 2439.316991242455, 2439.8414103083333, 2089.4880754215237, 2515.949681006379, 2535.3408113890337, 2541.466292078666, 2543.923682289534, 2474.7401522840023, 2483.5653760591913, 2487.291122030231, 2489.7136829264687, 2504.111040580595, 2378.203301893868, 2484.3913241255827, 2493.4789778235263, 2497.9883147325363, 2504.286278410644, 11644.88355116455, 11744.060475161967, 11753.823692644617, 11759.882401175964, 11812.173147879652, 6492.383223593751, 6716.899246511306, 6736.191657001143, 6744.966275168642, 6768.052472653832, 4136.669066474675, 4345.304751239786, 4388.912221755579, 4413.470383553994, 4416.381706561084, 1261.2999785119255, 1264.3046324521476, 1266.2339284732789, 1269.5620011096157, 1275.5918106206018, 12411.62765117057, 14834.480793172295, 16537.0, 16603.6679266414, 16607.169641517874, 4172.374143878443, 5209.817656382009, 5497.120460726264, 5696.971515142398, 5710.172025056344, 10641.787164256672, 11643.76712465746, 11863.58477453285, 11882.465289062013, 11947.880521194676, 6367.4906007519285, 6461.418472337512, 6517.087607734921, 6525.2169739631545, 6543.378379053982, 6086.69566521681, 6125.816225513242, 6169.81490555284, 6247.406496382836, 6248.28144763349, 4394.736315821061, 4430.689851710414, 4450.755208463565, 4505.36924830524, 4518.367428560005, 1049.8320268756963, 1281.077624110639, 1292.5992942187952, 1300.8504022037398, 1304.5760127958451, 793.3256731777942, 796.4066770256196, 818.6275244763618, 827.1645637905758, 850.2305413600701, 6275.592086514362, 6316.810495685137, 6502.382273683992, 6567.376099270562, 6578.934210657928, 583.5739909865822, 598.1476396135489, 599.6881621556767, 600.5075837813039, 608.5435923057706, 3977.244323578511, 4037.253108174997, 4114.917701645951, 4610.631149508032, 4626.306054091913, 14562.927185364006, 15236.923815380853, 15326.923365383102, 15402.768958465633, 15421.768673469907, 14759.409623614934, 14877.107373557621, 15197.088174709554, 15239.847601524065, 15268.770968435483, 8249.175082491704, 8507.191816777397, 8642.178992995656, 8643.870341945001, 8648.78378040554, 5837.241158649348, 6853.520253582202, 7120.074390329224, 7178.102737157856, 7267.382272506896, 10233.641822536176, 12947.676308091995, 12949.676258093623, 12956.0, 13198.472061117447, 13033.283169425773, 13044.282564459047, 13208.537701180412, 13247.006474514257, 13247.867521324664, 9322.766930826783, 9429.481378524248, 9518.95240523793, 9529.809403812023, 9533.523323833919, 8748.781280467785, 10698.679039628823, 10838.512266947848, 10866.130709543375, 10867.29362591429, 7511.737089201852, 8539.615717292612, 8911.50986695738, 9133.360664753669, 9289.442633442015, 9002.59488323014, 9022.593983270637, 9045.638174472948, 9082.18260356571, 9375.671851485438, 8202.958985205036, 8272.958635206785, 8367.372447066617, 8385.035720891932, 8402.075771665233, 6012.338642749207, 6015.308239552332, 6099.969500152471, 6164.3835616438, 6256.155419018374, 6050.697465126638, 6148.815535533941, 6262.0, 6326.335734747785, 6397.6481293529305], "value": 5305.081700135564, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.13770324949735, 403.5782607175501, 404.55701007396925, 404.8441698949499, 407.6901554818338, 6249.281332646747, 6254.374562543743, 6276.748930042804, 6347.49220062395, 6359.6184228946295, 5925.407459254073, 6034.909476357847, 6068.8482787930325, 6108.511319094473, 6139.0, 4590.586847183753, 4624.190766615829, 4633.050224703936, 4659.510751371108, 4662.883427914296, 4542.978374623294, 4591.793369298372, 4610.262358022717, 4655.464621568529, 4713.976430117862, 4556.2937744649635, 4592.311153326994, 4603.953960460402, 4610.077984403133, 4613.769311534426, 3979.4826672532677, 4558.860284928768, 4582.289745089516, 4585.541445855388, 4635.096156249516, 3851.614838516154, 4053.148838743867, 4055.047063939972, 4084.019835239551, 4099.344104943195, 4699.859004229845, 5772.3361813391575, 5776.364599894008, 5887.7939272125695, 6156.199694039791, 5314.468553144655, 5332.253484512172, 5332.813351532716, 5337.199420087018, 5337.786488540453, 5829.4753472187285, 6178.536609754242, 6566.0, 6634.966825165891, 6657.267700552935, 4346.56534346566, 4352.39066530686, 4353.651707863395, 4365.192439398689, 4371.34429835524, 5749.0906183795005, 5790.7973220937065, 6656.36764507371, 6834.6582670866255, 6882.724691012353, 3918.9027072419767, 4057.2899742544937, 4247.957520424818, 4261.829526818955, 4396.692231543761, 4285.0, 4495.505494395614, 4500.504944456107, 4503.500557190725, 4514.68397212192, 5020.7740651670765, 5302.602304827154, 5317.867053323621, 5344.118220493575, 5355.464453554613, 5732.254806875163, 6400.359964003563, 6553.724251977204, 6596.934030659728, 6635.734570617121, 1198.8920997110301, 1260.8171815086944, 1295.080492850087, 1509.6603264265452, 1525.80927384077, 961.427950369529, 998.201438848918, 1011.1506334678758, 1016.8576399304103, 1018.7860549284733, 6270.557771712441, 6281.403266689657, 6306.027202862259, 6329.905051424233, 6392.744290228339, 5996.550258730613, 6868.587884726932, 6878.58728476293, 6891.896621550681, 6910.445149841346, 5448.727563621803, 6012.188354572077, 6293.433590976834, 6294.929861923392, 6320.367963203735, 3570.4644303354185, 3600.567931848195, 3601.2977469393218, 3603.4594810778067, 3606.5311509503595, 6942.514024018371, 7053.0125782390505, 7053.224145344008, 7097.325754053357, 7117.71529138839, 7042.0, 7066.823329416703, 7074.327938845801, 7078.575285482888, 7120.821979450555, 5525.397634685896, 5724.2115981671805, 6423.74305027803, 6701.927691569325, 6984.895226571605, 5487.396386397413, 6833.965830170818, 6845.760398386232, 6885.793426197222, 6918.411934985583, 5586.357568879631, 6592.077109204619, 6609.917875869086, 6610.8347291318105, 6763.20774994617, 6430.421262086435, 7024.8595028098125, 7039.612821294878, 7042.119735033121, 7044.295570442915, 4255.25533031712, 4323.956760432356, 4370.300751879811, 4400.977995110132, 4416.911661766682, 6252.5935814171935, 6323.826274658703, 6600.537962342782, 6850.760223392157, 6891.414229790524, 5427.48438898304, 5742.569307301886, 5974.820755377345, 6334.271558770616, 6612.875811112026, 6050.850338435683, 6567.802965911031, 6644.0, 6708.765193218214, 6757.391834734897, 5454.781808727766, 6028.608140470856, 6492.383223593751, 6493.902591461037, 6577.6053436793945, 6309.432151106423, 6562.50781191403, 6667.566608170453, 6704.262531121671, 6716.529842910949, 5652.915206271827, 5987.940120598738, 6518.706658200297, 6529.151210342815, 6531.575447595891], "value": 4788.064460304323, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.4492658043176, 812.4353574265886, 837.4054421360837, 839.2153336630249, 849.8951363227802, 14651.853481465183, 15166.620834479116, 16592.668146637076, 16695.248713807876, 17019.0, 16117.435889743861, 17962.37131700391, 18017.81982180181, 18038.458846234633, 18077.4576762697, 978.7063880835774, 1002.2182697495965, 1014.219051330475, 1015.0103648942259, 1015.6343716262139, 993.662154867345, 1006.2553710254432, 1010.4947526236881, 1012.0284526854228, 1012.5291739341205, 963.0, 998.1615443027855, 1036.0261354326947, 1039.9064084232418, 1045.733337998813, 1011.1203253169716, 1031.9731866792515, 1039.2517387481003, 1046.1369370269558, 1057.5188289328337, 749.1646813802571, 761.8476304739019, 764.0869161352186, 767.0182166826471, 772.3589420780738, 17228.22472988706, 17564.209610567556, 17735.556611084696, 17800.732989005177, 18038.639227215383, 17674.469765907044, 17804.46586602404, 18152.09239538031, 18159.45521634353, 18192.818071819245, 12685.794849489284, 14080.15519068859, 14393.856061439355, 14655.41378344865, 14672.486462973744, 3450.8447119879665, 3477.6870081692514, 3483.3207524532727, 3518.489818976234, 3518.894433167009, 148049.0, 149895.0, 151090.24454877657, 151160.2441987794, 152874.0, 13431.059825812295, 13459.596212113653, 13469.461221551224, 13550.525731599346, 13560.389782459813, 24646.383840403774, 25263.0, 25952.0916767916, 25967.350816229744, 25995.0, 6786.932130678729, 7014.22843487216, 7039.260877607778, 7925.28672419485, 8101.392395570356, 2941.2940894185667, 3925.685945124383, 3928.489296391508, 3934.15415685629, 3934.783586902691, 4127.009517715726, 4185.937210941838, 4201.2017716633745, 4217.493900731932, 4225.873223803291, 928.8560273157536, 931.8741969834122, 943.9811203776023, 946.3470205558143, 946.8532377481501, 607.9209403309073, 629.7984644913676, 639.827246643413, 661.7816120680164, 680.7889554238202, 10775.191860610486, 11154.553817847358, 12232.938835305766, 12308.323042232763, 12365.134440589072, 568.1988396361111, 569.1263909898287, 572.093232226925, 586.6509426890993, 599.1492081244643, 5023.874403139951, 5516.939629883729, 5567.638103523258, 5711.657300561979, 5785.247917770747, 118644.0, 118966.0, 121633.0, 130842.0, 133942.0, 93802.0, 101364.0, 103048.0, 103123.45314820284, 104483.4775826116, 85876.85307867354, 87834.68247976285, 87866.56066719626, 90242.0, 92166.53916730508, 10311.896881031094, 11332.320060796379, 12204.145709800228, 12866.742665146458, 12931.935340323238, 42520.93697657583, 42901.498447554186, 43824.34263486114, 43874.0, 44202.55797442113, 44563.77718111389, 51336.486635134665, 54162.0, 54280.91438171294, 54585.72707136439, 16516.17419129063, 16605.0, 16839.326426943277, 16949.66100677955, 16953.321867125178, 18109.004004779865, 18166.455006349832, 18869.622607548048, 18876.433706988813, 18886.244550217838, 15699.607509812346, 16147.273372698492, 16490.093044882648, 16505.83494165043, 16560.17199140014, 17107.65784684324, 17171.656566868343, 17205.0, 17226.483205503853, 17386.739198911775, 13774.311284435538, 16480.34078636841, 16708.331666733193, 16916.915415422845, 17022.57443563921, 9962.352447090916, 11298.378589177346, 11973.820392694292, 12049.216800907912, 12106.455209515769, 11217.0, 12606.117571769888, 12764.978801696028, 12790.168639038433, 12908.290044047668], "value": 8462.576158361746, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1513.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 115.0, 112.0, 111.0, 115.0, 114.0, 114.0, 114.0, 116.0, 115.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 147.0, 111.0, 153.0, 112.0, 147.0, 114.0, 112.0, 153.0, 144.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 206.0, 213.0, 214.0, 250.0, 258.0, 210.0, 203.0, 196.0], "value": 214.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 62.0, 61.0, 85.0, 59.0, 59.0, 60.0, 58.0, 78.0, 56.0], "value": 65.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 84.0, 70.0, 68.0, 73.0, 87.0, 68.0, 73.0, 92.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 92.0, 88.0, 88.0, 88.0, 91.0, 88.0, 89.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 104.0, 100.0, 97.0, 95.0, 134.0, 96.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 148.0, 139.0, 96.0, 92.0, 92.0, 97.0, 90.0, 92.0, 93.0], "value": 101.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 52.0, 54.0, 50.0, 46.0, 46.0, 68.0, 70.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 59.0, 65.0, 63.0, 61.0, 56.0, 55.0, 59.0, 77.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 85.0, 86.0, 89.0, 86.0, 89.0, 89.0, 131.0, 86.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 141.0, 139.0, 150.0, 136.0, 141.0, 137.0, 162.0, 131.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 185.0, 187.0, 190.0, 188.0, 191.0, 191.0, 203.0, 173.0, 243.0], "value": 194.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 68.0, 69.0, 69.0, 64.0, 59.0, 62.0, 100.0, 94.0], "value": 72.3, "unit": "score"}]}]} 03:49:27 INFO - Return code: 0 03:49:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:28 INFO - # TBPL SUCCESS # 03:49:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:49:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:49:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:49:28 INFO - 2017-05-30 03:49:28.046 screenresolution[3185:50803] starting screenresolution argv=screenresolution get 03:49:28 INFO - 2017-05-30 03:49:28.049 screenresolution[3185:50803] Display 0: 1600x1200x32@60 03:49:28 INFO - 2017-05-30 03:49:28.055 screenresolution[3186:50806] starting screenresolution argv=screenresolution list 03:49:28 INFO - Available Modes on Display 0 03:49:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:49:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:49:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:49:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:49:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:49:28 INFO - Intel Iris: 03:49:28 INFO - Chipset Model: Intel Iris 03:49:28 INFO - Type: GPU 03:49:28 INFO - Bus: Built-In 03:49:28 INFO - VRAM (Dynamic, Max): 1536 MB 03:49:28 INFO - Vendor: Intel (0x8086) 03:49:28 INFO - Device ID: 0x0a2e 03:49:28 INFO - Revision ID: 0x0009 03:49:28 INFO - Displays: 03:49:28 INFO - Display: 03:49:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:49:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:49:28 INFO - Display Serial Number: 1600x1200 60 03:49:28 INFO - Main Display: Yes 03:49:28 INFO - Mirror: Off 03:49:28 INFO - Online: Yes 03:49:28 INFO - Rotation: Supported 03:49:28 INFO - Return code: 0 03:49:28 INFO - Running post-action listener: _resource_record_post_action 03:49:28 INFO - [mozharness: 2017-05-30 10:49:28.295029Z] Finished run-tests step (success) 03:49:28 INFO - Running post-run listener: _resource_record_post_run 03:49:28 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 1093120; Write bytes: 572755968; Read time: 29; Write time: 1990 03:49:28 INFO - TinderboxPrint: CPU usage
53.4% 03:49:28 INFO - TinderboxPrint: I/O read bytes / time
1,093,120 / 29 03:49:28 INFO - TinderboxPrint: I/O write bytes / time
572,755,968 / 1,990 03:49:28 INFO - TinderboxPrint: CPU idle
2,342.4 (46.6%) 03:49:28 INFO - TinderboxPrint: CPU system
1,023.8 (20.4%) 03:49:28 INFO - TinderboxPrint: CPU user
1,659.5 (33.0%) 03:49:28 INFO - TinderboxPrint: Swap in / out
246,509,568 / 0 03:49:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232780288; Write bytes: 231647232; Read time: 16860; Write time: 476 03:49:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:49:28 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 1092608; Write bytes: 321779712; Read time: 29; Write time: 1468 03:49:28 INFO - Running post-run listener: _upload_blobber_files 03:49:28 INFO - Blob upload gear active. 03:49:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:49:28 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'] 03:49:28 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 03:49:29 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:49:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:49:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:29 INFO - (blobuploader) - INFO - Done attempting. 03:49:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:49:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:49:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:30 INFO - (blobuploader) - INFO - Done attempting. 03:49:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:49:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:49:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:31 INFO - (blobuploader) - INFO - Done attempting. 03:49:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:49:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:33 INFO - (blobuploader) - INFO - Done attempting. 03:49:33 INFO - (blobuploader) - INFO - Iteration through files over. 03:49:33 INFO - Return code: 0 03:49:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:49:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:49:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c80bdd31da39a6f6b37ee84b7cf74d2264e0939a25c8edf154f3fdf22e81c9df018e56cafe8e55ef9d1abcaf5a0dbc8bc87fe6818f2bef7e16e792bbcbc667c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b7f46154f6df55a9a42aaf48c2ef0710763c4c921465efaab2013e6197f10ef7931abdb3d88328973edfdc0ef2cef4a992714cf6555513d670e1eedfe8911cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0471003c1fd94632f65b8ea8bcf2bfcbf548ced3c24cc6e425be317c12b61f23d5d3b0d3aa9ed7e23cd7e366efdab137a0bc15b18dc6390fc7bd7a85b01ca9bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb7fb564d390609060cc04024582dd4af7c3a33857f0cb251406ec306f56175346ca0455fbd158a0cee3d37e925cb6c9e4bc227e0118633729d9511c1561a6f9"} 03:49:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:49:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:49:33 INFO - Contents: 03:49:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c80bdd31da39a6f6b37ee84b7cf74d2264e0939a25c8edf154f3fdf22e81c9df018e56cafe8e55ef9d1abcaf5a0dbc8bc87fe6818f2bef7e16e792bbcbc667c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b7f46154f6df55a9a42aaf48c2ef0710763c4c921465efaab2013e6197f10ef7931abdb3d88328973edfdc0ef2cef4a992714cf6555513d670e1eedfe8911cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0471003c1fd94632f65b8ea8bcf2bfcbf548ced3c24cc6e425be317c12b61f23d5d3b0d3aa9ed7e23cd7e366efdab137a0bc15b18dc6390fc7bd7a85b01ca9bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb7fb564d390609060cc04024582dd4af7c3a33857f0cb251406ec306f56175346ca0455fbd158a0cee3d37e925cb6c9e4bc227e0118633729d9511c1561a6f9"} 03:49:33 INFO - Running post-run listener: copy_logs_to_upload_dir 03:49:33 INFO - Copying logs to upload dir... 03:49:33 INFO - mkdir: /builds/slave/test/build/upload/logs 03:49:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.573825 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-30 03:49:33.731877) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 03:49:33.735414) ========= 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.DKNLjntTqc/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.0pRBHY3QQO/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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/9c80bdd31da39a6f6b37ee84b7cf74d2264e0939a25c8edf154f3fdf22e81c9df018e56cafe8e55ef9d1abcaf5a0dbc8bc87fe6818f2bef7e16e792bbcbc667c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b7f46154f6df55a9a42aaf48c2ef0710763c4c921465efaab2013e6197f10ef7931abdb3d88328973edfdc0ef2cef4a992714cf6555513d670e1eedfe8911cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0471003c1fd94632f65b8ea8bcf2bfcbf548ced3c24cc6e425be317c12b61f23d5d3b0d3aa9ed7e23cd7e366efdab137a0bc15b18dc6390fc7bd7a85b01ca9bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb7fb564d390609060cc04024582dd4af7c3a33857f0cb251406ec306f56175346ca0455fbd158a0cee3d37e925cb6c9e4bc227e0118633729d9511c1561a6f9"} build_url:https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011667 build_url: 'https://queue.taskcluster.net/v1/task/AXesgECUT8e97XpEPAzAjg/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/9c80bdd31da39a6f6b37ee84b7cf74d2264e0939a25c8edf154f3fdf22e81c9df018e56cafe8e55ef9d1abcaf5a0dbc8bc87fe6818f2bef7e16e792bbcbc667c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b7f46154f6df55a9a42aaf48c2ef0710763c4c921465efaab2013e6197f10ef7931abdb3d88328973edfdc0ef2cef4a992714cf6555513d670e1eedfe8911cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0471003c1fd94632f65b8ea8bcf2bfcbf548ced3c24cc6e425be317c12b61f23d5d3b0d3aa9ed7e23cd7e366efdab137a0bc15b18dc6390fc7bd7a85b01ca9bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb7fb564d390609060cc04024582dd4af7c3a33857f0cb251406ec306f56175346ca0455fbd158a0cee3d37e925cb6c9e4bc227e0118633729d9511c1561a6f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 03:49:33.769127) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:49:33.769440) ========= 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.DKNLjntTqc/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.0pRBHY3QQO/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005079 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:49:33.817684) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 03:49:33.817984) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 03:49:33.818299) ========= ========= Total master_lag: 0.18 =========