builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1496167002.69 results: success (0) revision: ae492e1619671ca2b5f55369b70d97c7da67dd72 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:42.687188) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:42.687655) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:42.687925) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:42.713217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:42.713609) ========= 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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:56:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 62.1M=0s 2017-05-30 10:56:42 (62.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158603 ========= master_lag: 0.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:43.280841) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:43.281151) ========= 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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.029494 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:43.331577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:43.331906) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ae492e1619671ca2b5f55369b70d97c7da67dd72 --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 ae492e1619671ca2b5f55369b70d97c7da67dd72 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eDfLSt6eyH/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.5pE2aDO0dW/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:56:43,417 truncating revision to first 12 chars 2017-05-30 10:56:43,417 Setting DEBUG logging. 2017-05-30 10:56:43,417 attempt 1/10 2017-05-30 10:56:43,417 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ae492e161967?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 10:56:43,846 unpacking tar archive at: mozilla-central-ae492e161967/testing/mozharness/ program finished with exit code 0 elapsedTime=0.713801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:44.067865) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:44.068217) ========= script_repo_revision: ae492e1619671ca2b5f55369b70d97c7da67dd72 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:44.068654) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:44.069066) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:56:44.088465) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 10:56:44.088879) ========= /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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:56:44 INFO - MultiFileLogger online at 20170530 10:56:44 in /builds/slave/test 10:56:44 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 10:56:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:56:44 INFO - {'append_to_log': False, 10:56:44 INFO - 'base_work_dir': '/builds/slave/test', 10:56:44 INFO - 'blob_upload_branch': 'Firefox', 10:56:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:56:44 INFO - 'branch': 'Firefox', 10:56:44 INFO - 'buildbot_json_path': 'buildprops.json', 10:56:44 INFO - 'config_files': ('talos/mac_config.py',), 10:56:44 INFO - 'default_actions': ('clobber', 10:56:44 INFO - 'read-buildbot-config', 10:56:44 INFO - 'download-and-extract', 10:56:44 INFO - 'populate-webroot', 10:56:44 INFO - 'create-virtualenv', 10:56:44 INFO - 'install', 10:56:44 INFO - 'setup-mitmproxy', 10:56:44 INFO - 'run-tests'), 10:56:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:56:44 INFO - 'download_minidump_stackwalk': True, 10:56:44 INFO - 'download_symbols': 'ondemand', 10:56:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:56:44 INFO - 'tooltool.py': '/tools/tooltool.py', 10:56:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:56:44 INFO - '/tools/misc-python/virtualenv.py')}, 10:56:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:56:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:56:44 INFO - 'gecko_profile': False, 10:56:44 INFO - 'gecko_profile_interval': 0, 10:56:44 INFO - 'installer_path': 'installer.exe', 10:56:44 INFO - 'log_level': 'info', 10:56:44 INFO - 'log_name': 'talos', 10:56:44 INFO - 'log_to_console': True, 10:56:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:56:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:56:44 INFO - 'opt_config_files': (), 10:56:44 INFO - 'pip_index': False, 10:56:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:56:44 INFO - 'cmd': ('bash', 10:56:44 INFO - '-c', 10:56:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:56:44 INFO - 'enabled': True, 10:56:44 INFO - 'halt_on_failure': False, 10:56:44 INFO - 'name': 'check_screen_resolution'},), 10:56:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:56:44 INFO - 'cmd': ('bash', 10:56:44 INFO - '-c', 10:56:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:56:44 INFO - 'enabled': True, 10:56:44 INFO - 'halt_on_failure': False, 10:56:44 INFO - 'name': 'check_screen_resolution'},), 10:56:44 INFO - 'run_cmd_checks_enabled': True, 10:56:44 INFO - 'suite': 'dromaeojs-e10s', 10:56:44 INFO - 'system_bits': '32', 10:56:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:56:44 INFO - 'title': 't-yosemite-r7-0029', 10:56:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:56:44 INFO - 'use_talos_json': True, 10:56:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:56:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:56:44 INFO - 'work_dir': 'build'} 10:56:44 INFO - [mozharness: 2017-05-30 17:56:44.289363Z] Running clobber step. 10:56:44 INFO - Running pre-action listener: _resource_record_pre_action 10:56:44 INFO - Running main action method: clobber 10:56:44 INFO - rmtree: /builds/slave/test/build 10:56:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:56:46 INFO - Running post-action listener: _resource_record_post_action 10:56:46 INFO - [mozharness: 2017-05-30 17:56:46.849254Z] Finished clobber step (success) 10:56:46 INFO - [mozharness: 2017-05-30 17:56:46.849389Z] Running read-buildbot-config step. 10:56:46 INFO - Running pre-action listener: _resource_record_pre_action 10:56:46 INFO - Running main action method: read_buildbot_config 10:56:46 INFO - Using buildbot properties: 10:56:46 INFO - { 10:56:46 INFO - "project": "", 10:56:46 INFO - "product": "firefox", 10:56:46 INFO - "who": "ryanvm@gmail.com", 10:56:46 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:56:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:56:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:56:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:56:46 INFO - "stage_platform": "macosx64", 10:56:46 INFO - "basedir": "/builds/slave/test", 10:56:46 INFO - "buildnumber": 5, 10:56:46 INFO - "platform": "yosemite_r7", 10:56:46 INFO - "parent_task_id": "MnP_BPRBT1qJkS--yD_oYg", 10:56:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:56:46 INFO - "slavebuilddir": "test", 10:56:46 INFO - "taskId": "Fw4tVT2mTcKRzYhmfkWJXw", 10:56:46 INFO - "branch": "mozilla-central", 10:56:46 INFO - "script_repo_revision": "production", 10:56:46 INFO - "revision": "ae492e1619671ca2b5f55369b70d97c7da67dd72", 10:56:46 INFO - "slavename": "t-yosemite-r7-0029", 10:56:46 INFO - "repo_path": "mozilla-central" 10:56:46 INFO - } 10:56:46 INFO - Finding installer, test and symbols from parent task. 10:56:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fw4tVT2mTcKRzYhmfkWJXw'}, attempt #1 10:56:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fw4tVT2mTcKRzYhmfkWJXw'}, attempt #1 10:56:48 INFO - Task dependencies: VfPc1eRtSy28KBgGF4qosg MnP_BPRBT1qJkS--yD_oYg 10:56:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg'}, attempt #1 10:56:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:56:48 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/test_packages.json 10:56:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/target.crashreporter-symbols.zip 10:56:48 INFO - Running post-action listener: _resource_record_post_action 10:56:48 INFO - [mozharness: 2017-05-30 17:56:48.569879Z] Finished read-buildbot-config step (success) 10:56:48 INFO - [mozharness: 2017-05-30 17:56:48.569962Z] Running download-and-extract step. 10:56:48 INFO - Running pre-action listener: _resource_record_pre_action 10:56:48 INFO - Running main action method: download_and_extract 10:56:48 INFO - mkdir: /builds/slave/test/build/tests 10:56:48 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')]} 10:56:48 INFO - trying https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:56:48 INFO - Downloading https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/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 10:56:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/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 10:56:49 INFO - Downloaded 1325 bytes. 10:56:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:56:49 INFO - Using the following test package requirements: 10:56:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:56:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:56:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:56:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:56:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'jsshell-mac.zip'], 10:56:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:56:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:56:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:56:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:56:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:56:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:56:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:56:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:56:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:56:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:56:49 INFO - Fetch https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:56:50 INFO - Content-Length response header: 57630787 10:56:50 INFO - Bytes received: 57630787 10:57:09 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 10:57:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:57:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:57:09 INFO - Fetch https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:57:11 INFO - Content-Length response header: 57630787 10:57:11 INFO - Bytes received: 57630787 10:57:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:57:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:57:28 INFO - Fetch https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:57:29 INFO - Content-Length response header: 14447425 10:57:29 INFO - Bytes received: 14447425 10:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:30 INFO - trying https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:57:30 INFO - Downloading https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:57:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/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 10:57:32 INFO - Downloaded 67942379 bytes. 10:57:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:57:32 INFO - mkdir: /builds/slave/test/properties 10:57:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:57:32 INFO - Writing to file /builds/slave/test/properties/build_url 10:57:32 INFO - Contents: 10:57:32 INFO - build_url:https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:57:32 INFO - Running post-action listener: _resource_record_post_action 10:57:32 INFO - Running post-action listener: set_extra_try_arguments 10:57:32 INFO - [mozharness: 2017-05-30 17:57:32.096298Z] Finished download-and-extract step (success) 10:57:32 INFO - [mozharness: 2017-05-30 17:57:32.096459Z] Running populate-webroot step. 10:57:32 INFO - Running pre-action listener: _resource_record_pre_action 10:57:32 INFO - Running main action method: populate_webroot 10:57:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:57:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:57:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:57:32 INFO - '--tppagecycles', 10:57:32 INFO - '7'], 10:57:32 INFO - 'tests': ['tsvgm']}}, 10:57:32 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:57:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:57:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:57:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:57:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:57:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['damp', 'tps']}, 10:57:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'tests': ['damp', 'tps']}, 10:57:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['dromaeo_dom']}, 10:57:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:57:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:57:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:57:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:57:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['a11yr', 10:57:32 INFO - 'ts_paint', 10:57:32 INFO - 'tpaint', 10:57:32 INFO - 'sessionrestore', 10:57:32 INFO - 'sessionrestore_no_auto_restore', 10:57:32 INFO - 'tabpaint']}, 10:57:32 INFO - 'other-e10s': {'tests': ['a11yr', 10:57:32 INFO - 'ts_paint', 10:57:32 INFO - 'tpaint', 10:57:32 INFO - 'sessionrestore', 10:57:32 INFO - 'sessionrestore_no_auto_restore', 10:57:32 INFO - 'tabpaint']}, 10:57:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:57:32 INFO - 'ts_paint', 10:57:32 INFO - 'tpaint', 10:57:32 INFO - 'sessionrestore', 10:57:32 INFO - 'sessionrestore_no_auto_restore', 10:57:32 INFO - 'tabpaint']}, 10:57:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:57:32 INFO - 'ts_paint', 10:57:32 INFO - 'tpaint', 10:57:32 INFO - 'sessionrestore', 10:57:32 INFO - 'sessionrestore_no_auto_restore', 10:57:32 INFO - 'tabpaint']}, 10:57:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['a11yr', 10:57:32 INFO - 'ts_paint', 10:57:32 INFO - 'tpaint', 10:57:32 INFO - 'sessionrestore', 10:57:32 INFO - 'sessionrestore_no_auto_restore', 10:57:32 INFO - 'tabpaint']}, 10:57:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['a11yr', 10:57:32 INFO - 'ts_paint', 10:57:32 INFO - 'tpaint', 10:57:32 INFO - 'sessionrestore', 10:57:32 INFO - 'sessionrestore_no_auto_restore', 10:57:32 INFO - 'tabpaint']}, 10:57:32 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:57:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:57:32 INFO - 'bloom_basic_ref']}, 10:57:32 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:57:32 INFO - 'talos_options': ['--mitmproxy', 10:57:32 INFO - 'mitmproxy-recording-1.mp', 10:57:32 INFO - '--firstNonBlankPaint'], 10:57:32 INFO - 'tests': ['Quantum_1']}, 10:57:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['tsvgx', 10:57:32 INFO - 'tsvgr_opacity', 10:57:32 INFO - 'tart', 10:57:32 INFO - 'tscrollx', 10:57:32 INFO - 'cart', 10:57:32 INFO - 'tsvg_static']}, 10:57:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:57:32 INFO - 'tsvgr_opacity', 10:57:32 INFO - 'tart', 10:57:32 INFO - 'tscrollx', 10:57:32 INFO - 'cart', 10:57:32 INFO - 'tsvg_static']}, 10:57:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'talos_options': ['--disable-e10s'], 10:57:32 INFO - 'tests': ['tp5o']}, 10:57:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:57:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'talos_options': ['--disable-e10s', 10:57:32 INFO - '--xperf_path', 10:57:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:57:32 INFO - 'tests': ['tp5n']}, 10:57:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:57:32 INFO - 'talos_options': ['--xperf_path', 10:57:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:57:32 INFO - 'tests': ['tp5n']}}, 10:57:32 INFO - 'talos.zip': {'path': '', 10:57:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:57:32 INFO - Running post-action listener: _resource_record_post_action 10:57:32 INFO - [mozharness: 2017-05-30 17:57:32.118793Z] Finished populate-webroot step (success) 10:57:32 INFO - [mozharness: 2017-05-30 17:57:32.118948Z] Running create-virtualenv step. 10:57:32 INFO - Running pre-action listener: _resource_record_pre_action 10:57:32 INFO - Running main action method: create_virtualenv 10:57:32 INFO - Creating virtualenv /builds/slave/test/build/venv 10:57:32 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 10:57:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:57:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:57:32 INFO - Using real prefix '/tools/python27' 10:57:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:57:33 INFO - Installing distribute.............................................................................................................................................................................................done. 10:57:35 INFO - Installing pip.................done. 10:57:35 INFO - Return code: 0 10:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:57:36 INFO - Reading from file tmpfile_stdout 10:57:36 INFO - Output received: 10:57:36 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) 10:57:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:57:36 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')]} 10:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:36 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')]} 10:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:36 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:36 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 10:57:36 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 10:57:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:36 INFO - 'HOME': '/Users/cltbld', 10:57:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:36 INFO - 'LOGNAME': 'cltbld', 10:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:36 INFO - 'MOZ_NO_REMOTE': '1', 10:57:36 INFO - 'NO_EM_RESTART': '1', 10:57:36 INFO - 'PAGER': '/bin/cat', 10:57:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:36 INFO - 'PWD': '/builds/slave/test', 10:57:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:36 INFO - 'SHELL': '/bin/bash', 10:57:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:36 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:36 INFO - 'USER': 'cltbld', 10:57:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:36 INFO - 'XPC_FLAGS': '0x0', 10:57:36 INFO - 'XPC_SERVICE_NAME': '0', 10:57:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:37 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 10:57:37 INFO - Cleaning up... 10:57:37 INFO - Return code: 0 10:57:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:57:37 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')]} 10:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:37 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')]} 10:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:37 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:37 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 10:57:37 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 10:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:37 INFO - 'HOME': '/Users/cltbld', 10:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:37 INFO - 'LOGNAME': 'cltbld', 10:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:37 INFO - 'MOZ_NO_REMOTE': '1', 10:57:37 INFO - 'NO_EM_RESTART': '1', 10:57:38 INFO - 'PAGER': '/bin/cat', 10:57:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:38 INFO - 'PWD': '/builds/slave/test', 10:57:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:38 INFO - 'SHELL': '/bin/bash', 10:57:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:38 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:38 INFO - 'USER': 'cltbld', 10:57:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:38 INFO - 'XPC_FLAGS': '0x0', 10:57:38 INFO - 'XPC_SERVICE_NAME': '0', 10:57:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:38 INFO - Downloading/unpacking psutil>=3.1.1 10:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:57:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:40 INFO - Installing collected packages: psutil 10:57:40 INFO - Running setup.py install for psutil 10:57:40 INFO - building 'psutil._psutil_osx' extension 10:57:40 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 10:57:40 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 10:57:40 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 10:57:41 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 10:57:41 INFO - building 'psutil._psutil_posix' extension 10:57:41 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 10:57:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:57:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:57:41 INFO - ^ 10:57:41 INFO - 1 warning generated. 10:57:41 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 10:57:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:41 INFO - Successfully installed psutil 10:57:41 INFO - Cleaning up... 10:57:41 INFO - Return code: 0 10:57:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:41 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:41 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 10:57:41 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 10:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:41 INFO - 'HOME': '/Users/cltbld', 10:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:41 INFO - 'LOGNAME': 'cltbld', 10:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:41 INFO - 'MOZ_NO_REMOTE': '1', 10:57:41 INFO - 'NO_EM_RESTART': '1', 10:57:41 INFO - 'PAGER': '/bin/cat', 10:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:41 INFO - 'PWD': '/builds/slave/test', 10:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:41 INFO - 'SHELL': '/bin/bash', 10:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:41 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:41 INFO - 'USER': 'cltbld', 10:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:41 INFO - 'XPC_FLAGS': '0x0', 10:57:41 INFO - 'XPC_SERVICE_NAME': '0', 10:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:57:41 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 10:57:41 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 10:57:41 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 10:57:41 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 10:57:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:57:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:57:43 INFO - Installing collected packages: mozsystemmonitor 10:57:43 INFO - Running setup.py install for mozsystemmonitor 10:57:43 INFO - Successfully installed mozsystemmonitor 10:57:43 INFO - Cleaning up... 10:57:43 INFO - Return code: 0 10:57:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:57:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:43 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:43 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 10:57:43 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 10:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:43 INFO - 'HOME': '/Users/cltbld', 10:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:43 INFO - 'LOGNAME': 'cltbld', 10:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:43 INFO - 'MOZ_NO_REMOTE': '1', 10:57:43 INFO - 'NO_EM_RESTART': '1', 10:57:43 INFO - 'PAGER': '/bin/cat', 10:57:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:43 INFO - 'PWD': '/builds/slave/test', 10:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:43 INFO - 'SHELL': '/bin/bash', 10:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:43 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:43 INFO - 'USER': 'cltbld', 10:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:43 INFO - 'XPC_FLAGS': '0x0', 10:57:43 INFO - 'XPC_SERVICE_NAME': '0', 10:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:43 INFO - Downloading/unpacking jsonschema==2.5.1 10:57:43 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 10:57:43 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 10:57:43 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 10:57:43 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 10:57:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:57:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:57:44 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 10:57:44 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 10:57:44 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 10:57:44 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 10:57:45 INFO - Downloading functools32-3.2.3-2.tar.gz 10:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:57:45 INFO - warning: no files found matching '*.txt' 10:57:45 INFO - no previously-included directories found matching 'build' 10:57:45 INFO - no previously-included directories found matching 'dist' 10:57:45 INFO - no previously-included directories found matching '.git*' 10:57:45 INFO - Installing collected packages: jsonschema, functools32 10:57:45 INFO - Running setup.py install for functools32 10:57:45 INFO - warning: no files found matching '*.txt' 10:57:45 INFO - no previously-included directories found matching 'build' 10:57:45 INFO - no previously-included directories found matching 'dist' 10:57:45 INFO - no previously-included directories found matching '.git*' 10:57:45 INFO - Successfully installed jsonschema functools32 10:57:45 INFO - Cleaning up... 10:57:45 INFO - Return code: 0 10:57:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:45 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:45 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 10:57:45 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 10:57:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:45 INFO - 'HOME': '/Users/cltbld', 10:57:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:45 INFO - 'LOGNAME': 'cltbld', 10:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:45 INFO - 'MOZ_NO_REMOTE': '1', 10:57:45 INFO - 'NO_EM_RESTART': '1', 10:57:45 INFO - 'PAGER': '/bin/cat', 10:57:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:45 INFO - 'PWD': '/builds/slave/test', 10:57:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:45 INFO - 'SHELL': '/bin/bash', 10:57:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:45 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:45 INFO - 'USER': 'cltbld', 10:57:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:45 INFO - 'XPC_FLAGS': '0x0', 10:57:45 INFO - 'XPC_SERVICE_NAME': '0', 10:57:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:57:45 INFO - Cleaning up... 10:57:45 INFO - Return code: 0 10:57:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:45 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:45 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 10:57:45 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 10:57:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:45 INFO - 'HOME': '/Users/cltbld', 10:57:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:45 INFO - 'LOGNAME': 'cltbld', 10:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:45 INFO - 'MOZ_NO_REMOTE': '1', 10:57:45 INFO - 'NO_EM_RESTART': '1', 10:57:45 INFO - 'PAGER': '/bin/cat', 10:57:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:45 INFO - 'PWD': '/builds/slave/test', 10:57:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:45 INFO - 'SHELL': '/bin/bash', 10:57:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:45 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:45 INFO - 'USER': 'cltbld', 10:57:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:45 INFO - 'XPC_FLAGS': '0x0', 10:57:45 INFO - 'XPC_SERVICE_NAME': '0', 10:57:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:46 INFO - Downloading/unpacking blobuploader==1.2.4 10:57:46 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 10:57:46 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 10:57:46 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 10:57:46 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 10:57:47 INFO - Downloading blobuploader-1.2.4.tar.gz 10:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:57:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:57:47 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 10:57:47 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 10:57:47 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 10:57:47 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 10:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:57:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:48 INFO - Downloading docopt-0.6.1.tar.gz 10:57:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:57:48 INFO - Installing collected packages: blobuploader, requests, docopt 10:57:48 INFO - Running setup.py install for blobuploader 10:57:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:57:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:57:48 INFO - Running setup.py install for requests 10:57:49 INFO - Running setup.py install for docopt 10:57:49 INFO - Successfully installed blobuploader requests docopt 10:57:49 INFO - Cleaning up... 10:57:49 INFO - Return code: 0 10:57:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:49 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:49 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 10:57:49 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 10:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:49 INFO - 'HOME': '/Users/cltbld', 10:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:49 INFO - 'LOGNAME': 'cltbld', 10:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:49 INFO - 'MOZ_NO_REMOTE': '1', 10:57:49 INFO - 'NO_EM_RESTART': '1', 10:57:49 INFO - 'PAGER': '/bin/cat', 10:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:49 INFO - 'PWD': '/builds/slave/test', 10:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:49 INFO - 'SHELL': '/bin/bash', 10:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:49 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:49 INFO - 'USER': 'cltbld', 10:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:49 INFO - 'XPC_FLAGS': '0x0', 10:57:49 INFO - 'XPC_SERVICE_NAME': '0', 10:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1Ytqku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mZ6S6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-DNzHzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-G87v2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wIxdGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mwMLez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qqCY7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-HmGTQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7ul2u3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-9BT5sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JmtXtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wfS29C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kVY2ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-CEq6p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-N2wfzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-WFNu4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-oaI9x5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:57:51 INFO - Running setup.py install for manifestparser 10:57:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:57:51 INFO - Running setup.py install for mozcrash 10:57:51 INFO - Running setup.py install for mozdebug 10:57:51 INFO - Running setup.py install for mozdevice 10:57:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:57:51 INFO - Running setup.py install for mozfile 10:57:51 INFO - Running setup.py install for mozhttpd 10:57:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Running setup.py install for mozinfo 10:57:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Running setup.py install for mozInstall 10:57:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Running setup.py install for mozleak 10:57:52 INFO - Running setup.py install for mozlog 10:57:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Running setup.py install for moznetwork 10:57:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:57:52 INFO - Running setup.py install for mozprocess 10:57:53 INFO - Running setup.py install for mozprofile 10:57:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:57:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:57:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:57:53 INFO - Running setup.py install for mozrunner 10:57:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:57:53 INFO - Running setup.py install for mozscreenshot 10:57:53 INFO - Running setup.py install for moztest 10:57:53 INFO - Running setup.py install for mozversion 10:57:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:57:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:57:53 INFO - Cleaning up... 10:57:53 INFO - Return code: 0 10:57:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57: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')]} 10:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57: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')]} 10:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:53 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:53 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 10:57:53 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 10:57:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:53 INFO - 'HOME': '/Users/cltbld', 10:57:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:53 INFO - 'LOGNAME': 'cltbld', 10:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:53 INFO - 'MOZ_NO_REMOTE': '1', 10:57:53 INFO - 'NO_EM_RESTART': '1', 10:57:53 INFO - 'PAGER': '/bin/cat', 10:57:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:53 INFO - 'PWD': '/builds/slave/test', 10:57:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:53 INFO - 'SHELL': '/bin/bash', 10:57:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:53 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:53 INFO - 'USER': 'cltbld', 10:57:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:53 INFO - 'XPC_FLAGS': '0x0', 10:57:53 INFO - 'XPC_SERVICE_NAME': '0', 10:57:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jr4vFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ySSgqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-BXMCy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SV8qFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-HyhtY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4HoFsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-X_fWKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-quTpXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57:54 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)) 10:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-UhHdqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-VsaHlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Wa1qYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-DGD4aM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iVPTaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-VX_2JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-IGjey0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-f348En-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57:55 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)) 10:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-CArjVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57:55 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)) 10:57:55 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)) 10:57:55 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)) 10:57:55 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)) 10:57:55 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)) 10:57:55 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)) 10:57:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:57 INFO - Downloading blessings-1.6.tar.gz 10:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:57:57 INFO - Installing collected packages: blessings 10:57:57 INFO - Running setup.py install for blessings 10:57:57 INFO - Successfully installed blessings 10:57:57 INFO - Cleaning up... 10:57:57 INFO - Return code: 0 10:57:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:57:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:57:57 INFO - Reading from file tmpfile_stdout 10:57:57 INFO - Current package versions: 10:57:57 INFO - blessings == 1.6 10:57:57 INFO - blobuploader == 1.2.4 10:57:57 INFO - docopt == 0.6.1 10:57:57 INFO - functools32 == 3.2.3-2 10:57:57 INFO - jsonschema == 2.5.1 10:57:57 INFO - manifestparser == 1.1 10:57:57 INFO - mozInstall == 1.12 10:57:57 INFO - mozcrash == 1.0 10:57:57 INFO - mozdebug == 0.1 10:57:57 INFO - mozdevice == 0.51 10:57:57 INFO - mozfile == 1.2 10:57:57 INFO - mozhttpd == 0.7 10:57:57 INFO - mozinfo == 0.9 10:57:57 INFO - mozleak == 0.1 10:57:57 INFO - mozlog == 3.4 10:57:57 INFO - moznetwork == 0.27 10:57:57 INFO - mozprocess == 0.25 10:57:57 INFO - mozprofile == 0.28 10:57:57 INFO - mozrunner == 6.13 10:57:57 INFO - mozscreenshot == 0.1 10:57:57 INFO - mozsystemmonitor == 0.3 10:57:57 INFO - moztest == 0.8 10:57:57 INFO - mozversion == 1.4 10:57:57 INFO - psutil == 3.1.1 10:57:57 INFO - requests == 1.2.3 10:57:57 INFO - wsgiref == 0.1.2 10:57:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:57 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 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:57 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 10:57:57 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 10:57:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:57 INFO - 'HOME': '/Users/cltbld', 10:57:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:57 INFO - 'LOGNAME': 'cltbld', 10:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:57 INFO - 'MOZ_NO_REMOTE': '1', 10:57:57 INFO - 'NO_EM_RESTART': '1', 10:57:57 INFO - 'PAGER': '/bin/cat', 10:57:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:57 INFO - 'PWD': '/builds/slave/test', 10:57:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:57 INFO - 'SHELL': '/bin/bash', 10:57:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:57 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:57 INFO - 'USER': 'cltbld', 10:57:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:57 INFO - 'XPC_FLAGS': '0x0', 10:57:57 INFO - 'XPC_SERVICE_NAME': '0', 10:57:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:58 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)) 10:57:58 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)) 10:57:58 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)) 10:57:58 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)) 10:57:58 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)) 10:57:58 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)) 10:57:58 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)) 10:57:58 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)) 10:57:58 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)) 10:57:58 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:57:58 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 10:57:58 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 10:57:58 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 10:57:58 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 10:57:59 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:57:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:57:59 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)) 10:57:59 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)) 10:57:59 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)) 10:57:59 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)) 10:57:59 INFO - Installing collected packages: marionette-driver 10:57:59 INFO - Running setup.py install for marionette-driver 10:57:59 INFO - Successfully installed marionette-driver 10:57:59 INFO - Cleaning up... 10:57:59 INFO - Return code: 0 10:57:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7c0480750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, '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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:59 INFO - 'HOME': '/Users/cltbld', 10:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:59 INFO - 'LOGNAME': 'cltbld', 10:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:59 INFO - 'MOZ_NO_REMOTE': '1', 10:57:59 INFO - 'NO_EM_RESTART': '1', 10:57:59 INFO - 'PAGER': '/bin/cat', 10:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:59 INFO - 'PWD': '/builds/slave/test', 10:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:59 INFO - 'SHELL': '/bin/bash', 10:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:57:59 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:59 INFO - 'USER': 'cltbld', 10:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:59 INFO - 'XPC_FLAGS': '0x0', 10:57:59 INFO - 'XPC_SERVICE_NAME': '0', 10:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:58:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:58:00 INFO - Cleaning up... 10:58:00 INFO - Return code: 0 10:58:00 INFO - Running post-action listener: _resource_record_post_action 10:58:00 INFO - Running post-action listener: _start_resource_monitoring 10:58:00 INFO - Starting resource monitoring. 10:58:00 INFO - [mozharness: 2017-05-30 17:58:00.284974Z] Finished create-virtualenv step (success) 10:58:00 INFO - [mozharness: 2017-05-30 17:58:00.286175Z] Running install step. 10:58:00 INFO - Running pre-action listener: _resource_record_pre_action 10:58:00 INFO - Running main action method: install 10:58:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:58:00 INFO - Reading from file tmpfile_stdout 10:58:00 INFO - Detecting whether we're running mozinstall >=1.0... 10:58:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:58:00 INFO - Reading from file tmpfile_stdout 10:58:00 INFO - Output received: 10:58:00 INFO - Usage: mozinstall [options] installer 10:58:00 INFO - Options: 10:58:00 INFO - -h, --help show this help message and exit 10:58:00 INFO - -d DEST, --destination=DEST 10:58:00 INFO - Directory to install application into. [default: 10:58:00 INFO - "/builds/slave/test"] 10:58:00 INFO - --app=APP Application being installed. [default: firefox] 10:58:00 INFO - mkdir: /builds/slave/test/build/application 10:58:00 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'] 10:58:00 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 10:58:22 INFO - Reading from file tmpfile_stdout 10:58:22 INFO - Output received: 10:58:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:58:22 INFO - Running post-action listener: _resource_record_post_action 10:58:22 INFO - [mozharness: 2017-05-30 17:58:22.198922Z] Finished install step (success) 10:58:22 INFO - [mozharness: 2017-05-30 17:58:22.199073Z] Running setup-mitmproxy step. 10:58:22 INFO - Running pre-action listener: _resource_record_pre_action 10:58:22 INFO - Running main action method: setup_mitmproxy 10:58:22 INFO - Skipping: mitmproxy is not required 10:58:22 INFO - Running post-action listener: _resource_record_post_action 10:58:22 INFO - [mozharness: 2017-05-30 17:58:22.199665Z] Finished setup-mitmproxy step (success) 10:58:22 INFO - [mozharness: 2017-05-30 17:58:22.199820Z] Running run-tests step. 10:58:22 INFO - Running pre-action listener: _resource_record_pre_action 10:58:22 INFO - Running main action method: run_tests 10:58:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:58:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:58:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:58:22 INFO - 2017-05-30 10:58:22.214 screenresolution[1623:7470] starting screenresolution argv=screenresolution get 10:58:22 INFO - 2017-05-30 10:58:22.217 screenresolution[1623:7470] Display 0: 1600x1200x32@60 10:58:22 INFO - 2017-05-30 10:58:22.222 screenresolution[1624:7473] starting screenresolution argv=screenresolution list 10:58:22 INFO - Available Modes on Display 0 10:58:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:58:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:58:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:58:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:58:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:58:22 INFO - Intel Iris: 10:58:22 INFO - Chipset Model: Intel Iris 10:58:22 INFO - Type: GPU 10:58:22 INFO - Bus: Built-In 10:58:22 INFO - VRAM (Dynamic, Max): 1536 MB 10:58:22 INFO - Vendor: Intel (0x8086) 10:58:22 INFO - Device ID: 0x0a2e 10:58:22 INFO - Revision ID: 0x0009 10:58:22 INFO - Displays: 10:58:22 INFO - Display: 10:58:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:58:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:58:22 INFO - Display Serial Number: 1600x1200 60 10:58:22 INFO - Main Display: Yes 10:58:22 INFO - Mirror: Off 10:58:22 INFO - Online: Yes 10:58:22 INFO - Rotation: Supported 10:58:22 INFO - Return code: 0 10:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:58:22 INFO - Python 2.7.3 10:58:22 INFO - Return code: 0 10:58:22 INFO - grabbing minidump binary from tooltool 10:58:22 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')]} 10:58:22 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 0x10f85bc60>, '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 0x10f5f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f619be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:58:22 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 10:58:22 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 10:58:22 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 10:58:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:58:22 INFO - Return code: 0 10:58:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:58:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:58:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:58:22 INFO - ENV: RUST_BACKTRACE is now 1 10:58:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:58:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:58:22 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/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 10:58:22 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/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 10:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eDfLSt6eyH/Render', 10:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:58:22 INFO - 'HOME': '/Users/cltbld', 10:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:58:22 INFO - 'LOGNAME': 'cltbld', 10:58:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:58:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:58:22 INFO - 'MOZ_NO_REMOTE': '1', 10:58:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:58:22 INFO - 'NO_EM_RESTART': '1', 10:58:22 INFO - 'PAGER': '/bin/cat', 10:58:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:58:22 INFO - 'PWD': '/builds/slave/test', 10:58:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:58:22 INFO - 'RUST_BACKTRACE': '1', 10:58:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:58:22 INFO - 'SHELL': '/bin/bash', 10:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5pE2aDO0dW/Listeners', 10:58:22 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:58:22 INFO - 'USER': 'cltbld', 10:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:58:22 INFO - 'XPC_FLAGS': '0x0', 10:58:22 INFO - 'XPC_SERVICE_NAME': '0', 10:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:58:22 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/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 10:58:22 INFO - mozversion application_buildid: 20170530094035 10:58:22 INFO - mozversion application_changeset: ae492e1619671ca2b5f55369b70d97c7da67dd72 10:58:22 INFO - mozversion application_display_name: Nightly 10:58:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:58:22 INFO - mozversion application_name: Firefox 10:58:22 INFO - mozversion application_remotingname: firefox 10:58:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:58:22 INFO - mozversion application_vendor: Mozilla 10:58:22 INFO - mozversion application_version: 55.0a1 10:58:22 INFO - mozversion platform_buildid: 20170530094035 10:58:22 INFO - mozversion platform_changeset: ae492e1619671ca2b5f55369b70d97c7da67dd72 10:58:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:58:22 INFO - mozversion platform_version: 55.0a1 10:58:22 INFO - using testdate: 1496167102 10:58:22 INFO - actual date: 1496167102 10:58:22 INFO - starting webserver on 'localhost:49222' 10:58:22 INFO - SUITE-START | Running 2 tests 10:58:22 INFO - TEST-START | dromaeo_css 10:58:22 INFO - operating with platform_type : mac_ 10:58:22 INFO - Initialising browser for dromaeo_css test... 10:58:22 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp8gQyDj/profile 10:58:22 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 10:58:56 INFO - TEST-INFO | 1634: killed by SIGTERM 10:58:56 INFO - Browser initialized. 10:58:56 INFO - Running cycle 1/1 for dromaeo_css test... 10:58:56 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp8gQyDj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:58:59 INFO - PID 1644 | 2017-05-30 10:58:59.136 plugin-container[1645:8053] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 10:58:59 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:58:59 INFO - PID 1644 | 2017-05-30 10:58:59.137 plugin-container[1645:8053] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 10:58:59 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:58:59 INFO - PID 1644 | 2017-05-30 10:58:59.137 plugin-container[1645:8053] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:58:59 INFO - PID 1644 | 2017-05-30 10:58:59.815 plugin-container[1646:8093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 10:58:59 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:58:59 INFO - PID 1644 | 2017-05-30 10:58:59.815 plugin-container[1646:8093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 10:58:59 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:58:59 INFO - PID 1644 | 2017-05-30 10:58:59.815 plugin-container[1646:8093] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:59:09 INFO - PID 1644 | RSS: Main: 262762496 10:59:09 INFO - PID 1644 | 11:02:11 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 11:02:13 INFO - PID 1644 | RSS: Main: 273424384 11:02:13 INFO - PID 1644 | 11:05:15 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 11:05:15 INFO - PID 1644 | RSS: Main: 284676096 11:05:15 INFO - PID 1644 | 11:08:17 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 11:08:18 INFO - PID 1644 | RSS: Main: 283582464 11:08:18 INFO - PID 1644 | 11:11:19 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 11:11:20 INFO - PID 1644 | RSS: Main: 287264768 11:11:20 INFO - PID 1644 | 11:14:23 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 11:14:24 INFO - PID 1644 | RSS: Main: 287252480 11:14:24 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 11:17:25 INFO - PID 1644 | RSS: Main: 287264768 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | __start_tp_report 11:17:25 INFO - PID 1644 | _x_x_mozilla_page_load 11:17:25 INFO - PID 1644 | _x_x_mozilla_page_load_details 11:17:25 INFO - PID 1644 | |i|pagename|runs| 11:17:25 INFO - PID 1644 | |0;dojo.html;3591.084273510255;3618.819059047047;3635.5455568053994;3653.2145588698427;3656.689181419579;4424.690271680984;5430.647007944481;5678.48893599576;6222.128901953719;6267.592606480575;6198.907016394758;6213.471854892328;6219.9689001554925;6274.152989346437;6298.086777417272;5454.754536045868;5483.561315094803;5495.175723641446;5508.614396992212;5523.558115350762;5587.106063029916;5670.461306175906;5678.914816277759;5696.946064977971;5723.713814309289;4304.375865499485;5342.732863356817;5444.700541470217;5544.417836127229;5669.801556945507;5508.421615730331;5528.585356098269;5552.7778888844805;5590.972045139789;5591.021571225019;4547.840825571089;5504.174373843915;5520.779168833242;5529.806456773994;5555.722213889289;5488.615796894219;6199.163112979735;6316.810495685137;6318.115463835067;6330.715117819709;5792.218050563204;6550.7707230246715;6619.172603424572;6663.06717059612;6767.458603311772;6819.147606549182;6843.768121738085;6865.897011544831;6870.5534140281225;6875.5874647521305;5962.135490353918;6325.715342809584;6326.873462530723;6332.018537126753;6343.365663433619;6754.662266886636;6890.10428644273;6941.0629565009085;6989.860202795916;7037.049998250171;6456.9354306456335;6503.674816259168;6510.08858759774;6579.605223686498;6641.933580664131;5763.7982670606325;6774.220964589135;6854.383105520527;6857.965710171417;6874.2094659113845;6693;6734.6632668367365;6741.629210393476;6761.357671021246;6765.695543700447;5625.268715067097;6007.88854062;6548.476121910236;6612.966935165294;6676.86646267072;5401.270828438111;5402.216678581663;5424;5426.267453893753;5442.12925931849;4802.687825291345;4806.927896081562;4809.278608208727;4813.614910807127;4861.538153875376;5273.446288139767;6143.757528502154;6363.268224154282;6436.645984470899;6438.291787903327;6924.099867017256;6927.896081558781;6935.237123916365;6938.930610693929;6940.30596940312;6429.7428102875365;6459.386358295955;6461.547691661539;6466.773662921775;6470.0618410330235;5192.935448233103;5913;5987.6108052976415;6084.817455476343;6098.756049758049;7031.40233080194;7045.471589630901;7054.047703559953;7068.929310706827;7080.575165490087;6790.185177778698;6830.45356371499;6872.278410766798;6884.51808373409;6887.311268873073;6802.115724955922;6890.44876409896;6920.9307906920285;7028.332308430691;7072.504924655224;6682.2649508555;6758.425533829482;6768.120144381199;6788.76239331621;6795.694193761194;6813.1142951415995;6861.622610756456;6901.482388820959;6908.3091690830515;6919.481038922;6450.032495125769;6520.9347906520325;6568.6058836469965;6641.734330626721;6656.068150458843;6667.399934005963;6670.83322916931;6673.699683514156;6696.732130714717;6704.497162712719;5601.907628012498;6474.546781725234;6552.246491653521;6574.243961944438;6595.87870062081;6195.411435913581;6234.501239900887;6473.158489396348;6646.401823835878;6714.83212919681;5349.518543331119;6477.546571739933;6491.837704057436;6511.772087976898;6521.413072823469 11:17:25 INFO - PID 1644 | |1;ext.html;736.3777607921306;776.4797585617636;845.8942632170977;1237.7029512916897;1289.3810970734053;33439.16402089949;37679.81160094199;39147.80426097869;39174.60825391742;39205.80397098011;39082;40719.18561628766;40727;40805.795971020176;40872.97817554563;7842.96078519608;7992.3206527444945;8170.959145204266;8381.078081411071;8391.11893251209;8334.874976875351;8457.365697572675;8476.110008449117;8477.915220847804;8487.321014318839;8404.369672274574;8430.83138337233;8446;8448.74653760385;8491.32069434447;8431.19903609156;8460.900082989203;8464.280536154372;8472.78818029548;8473.745787626382;8131.227533384319;8179.100298967109;8241.340692744627;8242.917570824275;8256.967879015123;36893.31066893316;36923;37020.44469332962;37075.443868341994;37113.25773484542;3480.791152530831;3489.1626009757474;3521.4893840393256;3522.559680039995;3526.2594855080465;30776.768929242804;30817.845910770528;30825.537616935762;30832;30840.920567780246;3088.058142266608;3119.4229067622564;3120.0327898351584;3138.7489000879873;3147.6380216275193;234344;238576;263723.3627663718;299142;300432;46929;47187.528124719;47535.76232118817;47597;47636;48654.51345486571;49071.01857962821;50077.49922500801;50095.74952125289;50260.49739502558;14052.227127508086;14144.656257655506;14291.857081429052;14292.999490035805;14317.85682143186;11264.54941802335;11421.543138274375;11444.656660300205;11466.94266528662;11505.252158609663;13845.100068495716;13930.024898257227;13931.512397066053;13975;14042.789358159636;6210.844728881814;6221.440070393686;6248.937510624835;6262.499000079983;6264.467520260829;967.9322447428613;974.7758015656296;1005.3465247588979;1035.0425856083243;1039.4387031003325;23779.524409511712;24678.629820552705;24790.132345367827;24826.255212343298;25148;1113.7883802077579;1114.437209209344;1134.597217987619;1149.8850114988602;1164.5691094295164;12485.25088494675;12598.937005314914;12626.305553194663;12641.810372844415;12736.36318184087;280098;335660.64339356293;336059;337493.2501349959;339104;54898.45101549013;55161.448385516436;55214.447855521736;55222.723886380314;55311.723441382535;1469.8530146985215;1494.2230040378943;1514.48507507448;1522.3301747231183;1524.6798172383924;23904.40238994039;24059.398515037265;24083.036678533364;24120.87939560291;24139.879300603385;32852.50721239231;38090.23819523538;38325.233495330496;38627.2274554513;38629;279291;281596;281874;282149;282318;33718.8314058438;35441.46837797383;35563.933082007585;35858.10354741153;36115;42799.5720042808;44927.775361122985;45044.09911801811;45054.77472612747;45108.548914511746;29048;30222.697773021988;30811.383772324876;31456.370872581963;31822.045338639877;30752.384952301276;30795.846020769754;31242.906498272452;31522;32203.51694724628;36057.639423605426;36209.27581448409;37346.253074937806;37650;37803.810980944916;31091.37817243688;31623;31681.5247771279;32255;32313.51529727009;23179.768202317762;24345.75654243435;24462;24493.265202043254;24597.262082137564 11:17:25 INFO - PID 1644 | |2;jquery.html;407.6820080337338;415.8648439257242;424.70695220297983;430.20412237360887;440.9779511024448;17486.03826789526;20300.57895947284;20995.58008839824;21439.892800535996;21480.785192148072;21651;21887.67168492474;22487.437814054658;22778.65832012517;22846.885765571147;5699.173619825123;6000.639961602296;6044.848878778022;6063.4239747224165;6080.574359794816;5864.530837532987;5966.40335966402;5994.801039792037;6045.455908968192;6091.817245482643;5896.88206235875;5915.73379197935;5940.61386009908;5949.85125371867;5950.256217972754;4982.975085124587;5700.344460387067;5908.202392676976;5917.615355001933;5951.3751056139345;5358.91961620576;5371.462853714597;5374.516293533601;5374.946250537484;5378.327709036371;24845.751542484526;27025.0003749307;27633.309167270778;27921.301967450774;27952.161435156977;2049.7745248022707;2200.3508964855246;2206.007296716468;2211.513467037249;2211.966820497694;14499.42002319906;18051.819481805145;18074.548136296566;18220.180091895898;18447.354342597944;2127.319257837493;2145.6674215496623;2164.7077644517944;2166.068590506082;2168.6638571021517;151561.24219378832;152239.23880380637;152416.47583524135;152787.47212527844;152861.70707439398;10608;12684.492620295085;12874.356282185854;12918.22490650564;13063.542776002985;14717;15387.076775393512;15759.212039397984;16617.83382166163;16669.83330166707;8590.1839325264;8934;9093.36346455755;9132.452052876848;9139.543022848964;7019.473539484559;8544.145585441405;8565.357598180162;8569;8652.52411117382;7811.296983271419;7891.408144389194;7916.7229146980735;8060.637271322805;8084.191580841986;2195.9890200549216;2199.450137465634;2200.911963521441;2208.9889550552143;2242.4057624729458;964.8118616869783;987.8172538080478;997.7156510394502;1023.4166525080782;1042.1089968077335;20515.79484205139;21162.894185528974;21212;21454.678179827315;21471.46321341979;900.4552245891273;998.1465846700966;1013.0426746724274;1049.5644307612408;1053.167997282154;10516.3690178588;10980.835287470694;11005.559777608805;11009.2843965142;11026.117910567136;521343;525798;527977;528871;536466;128278.35860820823;128923.35538322249;129308;135110.3244483791;136599;1148.7645032768262;1160.4487868262509;1208.510553225945;1210.9757804843853;1219.670688914006;17557.82442175562;21735.565288694455;22534;22740.317790466313;22989;56059.43940560542;57868.13197802121;57888.84222315614;58332;58416.53958651068;57169;59768.10347844699;78878.6056069716;78979.60510197413;79970;8711.041785403728;8767.780805479913;8873.91126088731;9009.369344145847;9152.130547597968;10750;13363.198208107544;13940.30298485092;13940.860591393955;14364.784528231876;10945.179111566506;11070.059044981266;11484.540618375506;11941.880581194424;12112.394380280775;10921.399323037309;10960.232783705063;11035.39305338214;11282.323060616389;11387.259828111146;9399.34204605671;10083;11362.14783891195;12095.879041209475;12141.453634586289;8652.307815374881;8754.781130471789;8811.691590794548;9108.225800807006;9138.451692898447;10075;11267.141416058457;11858.881411185777;12145.939270303592;12174.695632609255 11:17:25 INFO - PID 1644 | |3;mootools.html;1194.498310709502;1442.8557144285567;1449.2536343782954;1452.3972551391166;1454.7236025155219;11711.59009434669;11897.405129743522;12069.3965301735;12153.906148446627;12175.025997920167;11933.224340417888;12059;12084.879151208484;12103.21329113607;12140.81788773167;2515.1322793636214;2545.2873195505194;2553.0553695132826;2558.9360265993314;2560.7567281108263;2495.800335973118;2547.350425641459;2548.0062775517563;2549.8087643426725;2550.3624093976505;2507.260358194337;2527.5071361084506;2537.568613335737;2540.5681034224226;2552.591585346345;2076.5950639625203;2478.8512689238614;2485.117783186961;2490.439651078511;2491.700995880497;2377.619580867053;2453.116877923946;2467.728549859514;2479.00839664134;2485.825992180548;11672.941635291769;11736.647900562997;11739.236949598335;11747.118966077493;11778.823317650242;5937.317208521033;6659.200895892475;6666.300038495937;6677.031830384615;6677.332266773284;4093.9590604093873;4267.637250833683;4342.413774140482;4343.435353404037;4348.673849461303;1238.5479300055506;1243.2664727810545;1246.1992420951728;1249.3628249592773;1250.4435526190848;15377;15670.921645391814;16287.185640718035;16420.343186272534;16535.090570018645;4368.847090351823;5266.131088370453;5476.698781567052;5683.658980461186;5700.686462244616;11778.764424711459;11808.409579520989;11813.76372472563;11844.111691623162;11847.407629618485;5400.91898621521;5562.97085039269;6421.068945003042;6454.483641308684;6527.902081468686;5958.612690175125;6080.179175811298;6123.2652081750475;6149.815505534841;6186.10301506288;4148.647364974011;4418.381426600278;4427.380166776654;4489.371487991683;4520.59314661682;1116.0513563470984;1259.3136740476461;1266.7466506698697;1289.6517940156104;1293.9870601294056;766.9348105410991;781.6091954022988;809.7773112394138;810.5784991804229;815.6696537902159;5991.4907232884825;6114.571979961449;6178.6601736904;6265.373462653789;6280.905786413207;581.8865321262314;585.096026638844;588.7497813429276;597.6384287506083;611.9296280927662;3815.980920095382;3819.968608475696;3856.305864944281;3872.0707030312524;4385.6710746694125;14867.851321486864;15294.617634559003;15381.46164884246;15403.537893863202;15410.691786164438;12223.93888030554;14960.925195374171;15257.6185595361;15333.386664533517;15423.997440166353;8355.248027677539;8481.448705834102;8494.150584941457;8511.361647876434;8642.56787160652;6241.407066328691;6814.352636499525;7140.107486564179;7183.568985860865;7193.136823581203;13391.665208369868;13425.462981480632;13537.729245415234;13599.728005440034;13901.513447029305;13993.300334983414;14092.647683807987;14108.576742697736;14267.714645707236;14297.499587514387;9049.819003620023;9379.812403752023;9493.905060949579;9497.952510237405;9512.85730714026;10619.256652034283;10740.570377185139;10762.51568679427;10768.623098191525;10775.946120269662;8893.199612034949;9124.27005839518;9181.586828592595;9214.170724634814;9336;8839.602217900081;8945.15021072997;8970.461772293684;9046.321525885453;9242.907570924204;8159.551224682699;8184.345252379914;8203.220694034057;8229.588520574067;8249.463784853966;5922.407759224043;6033.5173186145885;6043.184170137151;6066.939330606638;6203.627782333075;6059.121427393092;6127.754889804536;6159.322474527708;6233.75064997395;6237.095621135 11:17:25 INFO - PID 1644 | |4;prototype.html;355.7278681808417;391.4148348219412;391.528208508747;393.2155350076596;402.07923854373485;5893.263342082239;5936.940630593693;6217.316095229527;6227.221597300338;6275.968620156898;4948.901021979558;5784.380373495428;5918.763249470016;6055.9697201514045;6073.9066967945755;4090;4303;4573.131105090039;4584.404027476424;4604.815807367701;4564.771761411933;4578.97710511447;4600.930986035195;4601.171789077965;4609.193391156551;3893.2213557288514;4209.768462734563;4274.760319507354;4470.374147619336;4575.405197324343;4095.5904409559103;4439.66702497314;4452.97773511132;4458.977705111453;4466.508684044768;3465.5148279240925;3797.297499962488;3798.2403519295976;3818.968878402816;3826.8660596879254;5082.440931497569;5782.392848750863;5837.598976245713;5979.940200597982;6038.215032045806;4155.480564929384;4352.39066530686;4446.244138496463;4447.755373454491;5303.284056652341;5682.289713785777;6410.910145275315;6478.209658421654;6595.208574970986;6615.526848404578;3462.3594634992605;3835.520559930009;4231.894202644928;4324.891877703082;4327.221100201962;5912.793052243193;6113.388661133851;6590.242122155965;6842.042114104079;6970.477214208955;3791.9431208531896;4284.250256205151;4321.459817522809;4348.456442944632;4358.760268185217;3746.3069332173636;4444.133393988206;4453.487848897354;4457.153140903218;4473.172463094338;5098.031374038921;5226.3205783248695;5226.817061402909;5230.503102205284;5263.6841789492755;6678;6690.297518760557;6699.129113215251;6702.731890724414;6776.69504872282;1233.506597361045;1512.5613572064199;1523.3145084711923;1527.9388824446899;1534.4552683797315;853.5476197615274;956.3592393096642;997.3965750720854;998.5485632771529;1005.5073014222978;6228.6574238416415;6243.562950593506;6253.312135665074;6263.123162757218;6267.2165979252595;6117.418845209698;6886.827829304208;6909.585424874524;6921;6958.582485050812;5217.895642087213;5986.640801551921;6173.69131543421;6217.253929528395;6244.46922011625;3404.7105995990123;3572.3034008368118;3582.659647333499;3584.8386822593047;3599.0102721751728;5756.136579513107;5942.554308426886;6998.4751143664425;7014.92985070153;7070.92929070713;5651.745671444794;6367.713452894631;7004.579725216503;7059.364657180776;7111.502194846311;6496.967515162489;6792.45660347171;6998.510104292753;7055.753048643375;7062.964685176541;5398.433164517669;6685.431738302299;6839.418649414855;6863.279355667583;6877.3810357068105;5434.402215756343;6473.643949582818;6544.443722283469;6717.563358381885;6777.2883847195335;6649.202095748541;6843.657817109025;6936.7225310989015;6957.138787972724;7050.025992722046;3724.3296206681975;4366.410534577792;4377.540358262337;4381.978090109529;4404.405405270376;6591.868162636624;6834.521583489108;6840;6893.448524117958;6932.757353492805;5054.281544274964;6575.210974683068;6605.669716514251;6612.404883560503;6613.570117942319;5298.178782288752;5845.883082338244;6574.474042076718;6617.316510324511;6652.135222421054;6448.871022579428;6487.448566871869;6493.448056915215;6507.154069970874;6515.381038801306;6302.968485157545;6608.834779130368;6629.933700662931;6642.837503436976;6683.331666833278;5431.5926305526455;6050.425209605257;6426.389492998158;6468.256150542559;6486.0919471275 11:17:25 INFO - PID 1644 | |5;yui.html;815.5147687126158;818.055146306016;838.5597561280327;848.7014867253101;863.3611127765452;16551.006939583618;17074.146292685364;17090.230939607718;18780.248790048383;19606.803931960676;17873.82126178738;18272.17775200116;18352.724709129372;18418.631627367446;18442.262309507638;964.2960638733746;1004.6584161385127;1005.4419797012655;1006.8238058339762;1008.2186305613135;862.3489265604492;988.9604415823394;998.9450580218086;1004.3120462483186;1005.0150852164883;1051.0698032241444;1062.495314725503;1065.6270305393127;1067.8505009298703;1067.9946600267026;936.5083331251079;983.2379905573202;1059.3061544688242;1060.8514807926897;1061.87257529097;767.3209209849268;774.0363247756579;775.9806004849868;776.5961699916012;776.953382797034;14545.927270363687;16774.49676509707;17248.82751172485;17642.823571764246;17809.376671816422;17582.736258956127;17625.471235862944;17838.732419013726;17850.46448606531;17882.642347152985;14840.851591484055;14911.552653420415;14972.101673899491;14975.925120374437;14979.775303370348;3536.734744894143;3648.4892115103908;3697.149655579232;3701.5743189533277;3702.2780557791243;130296;133396.66603334036;133863.33068334693;134980.32509837387;136523;12628.936855315664;13007.414666340037;13685.521006764715;13774.449022039005;13899.652508687364;25420.364490887874;26110.086146984766;26241.868790655924;26409.471810563682;26482;6742.831429214309;7658.846823063507;7805.492642978188;7920.6039698014865;7956.403269754792;3945.64489195968;3967.821448034845;4030.717849750489;4034.35450327952;4048.3725022621543;4313.417688611998;4320.330348795942;4359.06280149763;4402.207602631493;4415.977920110379;853.3770347646129;870.8737233101292;876.7983363826355;877.7147427086222;880.2517859819103;641.4355367276767;649.8018104478085;666.1972323350351;666.6000399760202;676.7090151235017;10845.186611004206;11494.023008044243;11911.583094591648;11999.880001199876;12118.515259389702;611.39471922797;645.1967300710645;657.7566300468857;667.9398854103154;674.275154209227;4917.704937703749;5206.140986737221;5387.676739395649;5448.509634132947;5465.316835395575;125191;125852;127001;127381.36309318579;128350.35824820815;100279;107840.38239426415;108790;109133;112267.43866280616;95358.56962145574;95654.56518152234;97631.51184244176;97774.73365296541;97946.5102674482;9433.43399396011;10304.227182961458;10574.41840698769;10824.783504330027;10925.39910304909;30993;40138;40360.3945940817;40804;41051.384229237185;54144.72927635337;54552.45447545474;54696;54969.45030549643;55198.17202741882;13512.256825874278;13641.181529108286;13939.372728227458;13993.860061399662;13994;15343.00270482415;15538.844611553739;15930.761038584662;16034.438794642132;16057.518274451319;13274;14160.362783674966;14262;16903.323867045674;18395.080245987916;16105.275262612977;16289.429869954494;17285.308587656353;17959.820401796336;18162.09189540544;16979;17013.149342532575;17672.29310827604;17694;17820.643587127925;10260.076593106656;11720.824187637021;11785.764284714429;12185.14703970749;12389.75220495603;11618.477168527606;12924.806127907901;13584;13612.319384030958;13658.453661853415 11:17:25 INFO - PID 1644 | __end_tp_report 11:17:25 INFO - PID 1644 | __start_cc_report 11:17:25 INFO - PID 1644 | _x_x_mozilla_cycle_collect,459 11:17:25 INFO - PID 1644 | __end_cc_report 11:17:25 INFO - PID 1644 | __startTimestamp1496168245926__endTimestamp 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | ------- Summary: start ------- 11:17:25 INFO - PID 1644 | Number of tests: 6 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:6183.94 Median:6461.55 stddev:777.08 (12.0%) stddev-sans-first:752.37 11:17:25 INFO - PID 1644 | Values: 3591.1 3618.8 3635.5 3653.2 3656.7 4424.7 5430.6 5678.5 6222.1 6267.6 6198.9 6213.5 6220.0 6274.2 6298.1 5454.8 5483.6 5495.2 5508.6 5523.6 5587.1 5670.5 5678.9 5696.9 5723.7 4304.4 5342.7 5444.7 5544.4 5669.8 5508.4 5528.6 5552.8 5591.0 5591.0 4547.8 5504.2 5520.8 5529.8 5555.7 5488.6 6199.2 6316.8 6318.1 6330.7 5792.2 6550.8 6619.2 6663.1 6767.5 6819.1 6843.8 6865.9 6870.6 6875.6 5962.1 6325.7 6326.9 6332.0 6343.4 6754.7 6890.1 6941.1 6989.9 7037.0 6456.9 6503.7 6510.1 6579.6 6641.9 5763.8 6774.2 6854.4 6858.0 6874.2 6693.0 6734.7 6741.6 6761.4 6765.7 5625.3 6007.9 6548.5 6613.0 6676.9 5401.3 5402.2 5424.0 5426.3 5442.1 4802.7 4806.9 4809.3 4813.6 4861.5 5273.4 6143.8 6363.3 6436.6 6438.3 6924.1 6927.9 6935.2 6938.9 6940.3 6429.7 6459.4 6461.5 6466.8 6470.1 5192.9 5913.0 5987.6 6084.8 6098.8 7031.4 7045.5 7054.0 7068.9 7080.6 6790.2 6830.5 6872.3 6884.5 6887.3 6802.1 6890.4 6920.9 7028.3 7072.5 6682.3 6758.4 6768.1 6788.8 6795.7 6813.1 6861.6 6901.5 6908.3 6919.5 6450.0 6520.9 6568.6 6641.7 6656.1 6667.4 6670.8 6673.7 6696.7 6704.5 5601.9 6474.5 6552.2 6574.2 6595.9 6195.4 6234.5 6473.2 6646.4 6714.8 5349.5 6477.5 6491.8 6511.8 6521.4 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:44580.20 Median:185606.04 stddev:76849.79 (41.4%) stddev-sans-first:76994.86 11:17:25 INFO - PID 1644 | Values: 736.4 776.5 845.9 1237.7 1289.4 33439.2 37679.8 39147.8 39174.6 39205.8 39082.0 40719.2 40727.0 40805.8 40873.0 7843.0 7992.3 8171.0 8381.1 8391.1 8334.9 8457.4 8476.1 8477.9 8487.3 8404.4 8430.8 8446.0 8448.7 8491.3 8431.2 8460.9 8464.3 8472.8 8473.7 8131.2 8179.1 8241.3 8242.9 8257.0 36893.3 36923.0 37020.4 37075.4 37113.3 3480.8 3489.2 3521.5 3522.6 3526.3 30776.8 30817.8 30825.5 30832.0 30840.9 3088.1 3119.4 3120.0 3138.7 3147.6 234344.0 238576.0 263723.4 299142.0 300432.0 46929.0 47187.5 47535.8 47597.0 47636.0 48654.5 49071.0 50077.5 50095.7 50260.5 14052.2 14144.7 14291.9 14293.0 14317.9 11264.5 11421.5 11444.7 11466.9 11505.3 13845.1 13930.0 13931.5 13975.0 14042.8 6210.8 6221.4 6248.9 6262.5 6264.5 967.9 974.8 1005.3 1035.0 1039.4 23779.5 24678.6 24790.1 24826.3 25148.0 1113.8 1114.4 1134.6 1149.9 1164.6 12485.3 12598.9 12626.3 12641.8 12736.4 280098.0 335660.6 336059.0 337493.3 339104.0 54898.5 55161.4 55214.4 55222.7 55311.7 1469.9 1494.2 1514.5 1522.3 1524.7 23904.4 24059.4 24083.0 24120.9 24139.9 32852.5 38090.2 38325.2 38627.2 38629.0 279291.0 281596.0 281874.0 282149.0 282318.0 33718.8 35441.5 35563.9 35858.1 36115.0 42799.6 44927.8 45044.1 45054.8 45108.5 29048.0 30222.7 30811.4 31456.4 31822.0 30752.4 30795.8 31242.9 31522.0 32203.5 36057.6 36209.3 37346.3 37650.0 37803.8 31091.4 31623.0 31681.5 32255.0 32313.5 23179.8 24345.8 24462.0 24493.3 24597.3 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:34732.31 Median:2211.74 stddev:89871.20 (4063.4%) stddev-sans-first:90086.36 11:17:25 INFO - PID 1644 | Values: 407.7 415.9 424.7 430.2 441.0 17486.0 20300.6 20995.6 21439.9 21480.8 21651.0 21887.7 22487.4 22778.7 22846.9 5699.2 6000.6 6044.8 6063.4 6080.6 5864.5 5966.4 5994.8 6045.5 6091.8 5896.9 5915.7 5940.6 5949.9 5950.3 4983.0 5700.3 5908.2 5917.6 5951.4 5358.9 5371.5 5374.5 5374.9 5378.3 24845.8 27025.0 27633.3 27921.3 27952.2 2049.8 2200.4 2206.0 2211.5 2212.0 14499.4 18051.8 18074.5 18220.2 18447.4 2127.3 2145.7 2164.7 2166.1 2168.7 151561.2 152239.2 152416.5 152787.5 152861.7 10608.0 12684.5 12874.4 12918.2 13063.5 14717.0 15387.1 15759.2 16617.8 16669.8 8590.2 8934.0 9093.4 9132.5 9139.5 7019.5 8544.1 8565.4 8569.0 8652.5 7811.3 7891.4 7916.7 8060.6 8084.2 2196.0 2199.5 2200.9 2209.0 2242.4 964.8 987.8 997.7 1023.4 1042.1 20515.8 21162.9 21212.0 21454.7 21471.5 900.5 998.1 1013.0 1049.6 1053.2 10516.4 10980.8 11005.6 11009.3 11026.1 521343.0 525798.0 527977.0 528871.0 536466.0 128278.4 128923.4 129308.0 135110.3 136599.0 1148.8 1160.4 1208.5 1211.0 1219.7 17557.8 21735.6 22534.0 22740.3 22989.0 56059.4 57868.1 57888.8 58332.0 58416.5 57169.0 59768.1 78878.6 78979.6 79970.0 8711.0 8767.8 8873.9 9009.4 9152.1 10750.0 13363.2 13940.3 13940.9 14364.8 10945.2 11070.1 11484.5 11941.9 12112.4 10921.4 10960.2 11035.4 11282.3 11387.3 9399.3 10083.0 11362.1 12095.9 12141.5 8652.3 8754.8 8811.7 9108.2 9138.5 10075.0 11267.1 11858.9 12145.9 12174.7 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:7110.32 Median:3817.97 stddev:4555.46 (119.3%) stddev-sans-first:4546.55 11:17:25 INFO - PID 1644 | Values: 1194.5 1442.9 1449.3 1452.4 1454.7 11711.6 11897.4 12069.4 12153.9 12175.0 11933.2 12059.0 12084.9 12103.2 12140.8 2515.1 2545.3 2553.1 2558.9 2560.8 2495.8 2547.4 2548.0 2549.8 2550.4 2507.3 2527.5 2537.6 2540.6 2552.6 2076.6 2478.9 2485.1 2490.4 2491.7 2377.6 2453.1 2467.7 2479.0 2485.8 11672.9 11736.6 11739.2 11747.1 11778.8 5937.3 6659.2 6666.3 6677.0 6677.3 4094.0 4267.6 4342.4 4343.4 4348.7 1238.5 1243.3 1246.2 1249.4 1250.4 15377.0 15670.9 16287.2 16420.3 16535.1 4368.8 5266.1 5476.7 5683.7 5700.7 11778.8 11808.4 11813.8 11844.1 11847.4 5400.9 5563.0 6421.1 6454.5 6527.9 5958.6 6080.2 6123.3 6149.8 6186.1 4148.6 4418.4 4427.4 4489.4 4520.6 1116.1 1259.3 1266.7 1289.7 1294.0 766.9 781.6 809.8 810.6 815.7 5991.5 6114.6 6178.7 6265.4 6280.9 581.9 585.1 588.7 597.6 611.9 3816.0 3820.0 3856.3 3872.1 4385.7 14867.9 15294.6 15381.5 15403.5 15410.7 12223.9 14960.9 15257.6 15333.4 15424.0 8355.2 8481.4 8494.2 8511.4 8642.6 6241.4 6814.4 7140.1 7183.6 7193.1 13391.7 13425.5 13537.7 13599.7 13901.5 13993.3 14092.6 14108.6 14267.7 14297.5 9049.8 9379.8 9493.9 9498.0 9512.9 10619.3 10740.6 10762.5 10768.6 10775.9 8893.2 9124.3 9181.6 9214.2 9336.0 8839.6 8945.2 8970.5 9046.3 9242.9 8159.6 8184.3 8203.2 8229.6 8249.5 5922.4 6033.5 6043.2 6066.9 6203.6 6059.1 6127.8 6159.3 6233.8 6237.1 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5248.33 Median:5961.25 stddev:1698.59 (28.5%) stddev-sans-first:1663.19 11:17:25 INFO - PID 1644 | Values: 355.7 391.4 391.5 393.2 402.1 5893.3 5936.9 6217.3 6227.2 6276.0 4948.9 5784.4 5918.8 6056.0 6073.9 4090.0 4303.0 4573.1 4584.4 4604.8 4564.8 4579.0 4600.9 4601.2 4609.2 3893.2 4209.8 4274.8 4470.4 4575.4 4095.6 4439.7 4453.0 4459.0 4466.5 3465.5 3797.3 3798.2 3819.0 3826.9 5082.4 5782.4 5837.6 5979.9 6038.2 4155.5 4352.4 4446.2 4447.8 5303.3 5682.3 6410.9 6478.2 6595.2 6615.5 3462.4 3835.5 4231.9 4324.9 4327.2 5912.8 6113.4 6590.2 6842.0 6970.5 3791.9 4284.3 4321.5 4348.5 4358.8 3746.3 4444.1 4453.5 4457.2 4473.2 5098.0 5226.3 5226.8 5230.5 5263.7 6678.0 6690.3 6699.1 6702.7 6776.7 1233.5 1512.6 1523.3 1527.9 1534.5 853.5 956.4 997.4 998.5 1005.5 6228.7 6243.6 6253.3 6263.1 6267.2 6117.4 6886.8 6909.6 6921.0 6958.6 5217.9 5986.6 6173.7 6217.3 6244.5 3404.7 3572.3 3582.7 3584.8 3599.0 5756.1 5942.6 6998.5 7014.9 7070.9 5651.7 6367.7 7004.6 7059.4 7111.5 6497.0 6792.5 6998.5 7055.8 7063.0 5398.4 6685.4 6839.4 6863.3 6877.4 5434.4 6473.6 6544.4 6717.6 6777.3 6649.2 6843.7 6936.7 6957.1 7050.0 3724.3 4366.4 4377.5 4382.0 4404.4 6591.9 6834.5 6840.0 6893.4 6932.8 5054.3 6575.2 6605.7 6612.4 6613.6 5298.2 5845.9 6574.5 6617.3 6652.1 6448.9 6487.4 6493.4 6507.2 6515.4 6303.0 6608.8 6629.9 6642.8 6683.3 5431.6 6050.4 6426.4 6468.3 6486.1 11:17:25 INFO - PID 1644 | 11:17:25 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:23411.25 Median:17844.60 stddev:35080.32 (196.6%) stddev-sans-first:35137.71 11:17:25 INFO - PID 1644 | Values: 815.5 818.1 838.6 848.7 863.4 16551.0 17074.1 17090.2 18780.2 19606.8 17873.8 18272.2 18352.7 18418.6 18442.3 964.3 1004.7 1005.4 1006.8 1008.2 862.3 989.0 998.9 1004.3 1005.0 1051.1 1062.5 1065.6 1067.9 1068.0 936.5 983.2 1059.3 1060.9 1061.9 767.3 774.0 776.0 776.6 777.0 14545.9 16774.5 17248.8 17642.8 17809.4 17582.7 17625.5 17838.7 17850.5 17882.6 14840.9 14911.6 14972.1 14975.9 14979.8 3536.7 3648.5 3697.1 3701.6 3702.3 130296.0 133396.7 133863.3 134980.3 136523.0 12628.9 13007.4 13685.5 13774.4 13899.7 25420.4 26110.1 26241.9 26409.5 26482.0 6742.8 7658.8 7805.5 7920.6 7956.4 3945.6 3967.8 4030.7 4034.4 4048.4 4313.4 4320.3 4359.1 4402.2 4416.0 853.4 870.9 876.8 877.7 880.3 641.4 649.8 666.2 666.6 676.7 10845.2 11494.0 11911.6 11999.9 12118.5 611.4 645.2 657.8 667.9 674.3 4917.7 5206.1 5387.7 5448.5 5465.3 125191.0 125852.0 127001.0 127381.4 128350.4 100279.0 107840.4 108790.0 109133.0 112267.4 95358.6 95654.6 97631.5 97774.7 97946.5 9433.4 10304.2 10574.4 10824.8 10925.4 30993.0 40138.0 40360.4 40804.0 41051.4 54144.7 54552.5 54696.0 54969.5 55198.2 13512.3 13641.2 13939.4 13993.9 13994.0 15343.0 15538.8 15930.8 16034.4 16057.5 13274.0 14160.4 14262.0 16903.3 18395.1 16105.3 16289.4 17285.3 17959.8 18162.1 16979.0 17013.1 17672.3 17694.0 17820.6 10260.1 11720.8 11785.8 12185.1 12389.8 11618.5 12924.8 13584.0 13612.3 13658.5 11:17:25 INFO - PID 1644 | -------- Summary: end -------- 11:17:25 INFO - PID 1644 | 11:17:26 INFO - TEST-INFO | 1644: exit 0 11:17:26 INFO - TEST-OK | dromaeo_css | took 1143643ms 11:17:26 INFO - TEST-START | kraken 11:17:26 INFO - operating with platform_type : mac_ 11:17:26 INFO - Initialising browser for kraken test... 11:17:26 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmplHmfsQ/profile 11:17:26 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 11:18:00 INFO - TEST-INFO | 1655: killed by SIGTERM 11:18:00 INFO - Browser initialized. 11:18:00 INFO - Running cycle 1/1 for kraken test... 11:18:00 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmplHmfsQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:18:02 INFO - PID 1659 | 2017-05-30 11:18:02.799 plugin-container[1660:10683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 11:18:02 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:02 INFO - PID 1659 | 2017-05-30 11:18:02.800 plugin-container[1660:10683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0f, name = 'com.apple.CFPasteboardClient' 11:18:02 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:02 INFO - PID 1659 | 2017-05-30 11:18:02.800 plugin-container[1660:10683] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:18:03 INFO - PID 1659 | 2017-05-30 11:18:03.256 plugin-container[1661:10723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 11:18:03 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:03 INFO - PID 1659 | 2017-05-30 11:18:03.257 plugin-container[1661:10723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 11:18:03 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:03 INFO - PID 1659 | 2017-05-30 11:18:03.257 plugin-container[1661:10723] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:18:12 INFO - PID 1659 | RSS: Main: 261632000 11:18:12 INFO - PID 1659 | 11:18:15 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 11:18:16 INFO - PID 1659 | RSS: Main: 272146432 11:18:16 INFO - PID 1659 | 11:18:18 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 11:18:19 INFO - PID 1659 | RSS: Main: 279851008 11:18:19 INFO - PID 1659 | 11:18:22 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 11:18:23 INFO - PID 1659 | RSS: Main: 282066944 11:18:23 INFO - PID 1659 | 11:18:25 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 11:18:26 INFO - PID 1659 | RSS: Main: 282890240 11:18:26 INFO - PID 1659 | 11:18:28 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:18:28 INFO - PID 1659 | RSS: Main: 283320320 11:18:28 INFO - PID 1659 | 11:18:31 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 11:18:32 INFO - PID 1659 | RSS: Main: 288935936 11:18:32 INFO - PID 1659 | 11:18:35 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 11:18:35 INFO - PID 1659 | RSS: Main: 291631104 11:18:35 INFO - PID 1659 | 11:18:38 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 11:18:39 INFO - PID 1659 | RSS: Main: 291184640 11:18:39 INFO - PID 1659 | 11:18:40 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:18:41 INFO - PID 1659 | RSS: Main: 290566144 11:18:41 INFO - PID 1659 | 11:18:43 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:18:43 INFO - PID 1659 | RSS: Main: 290869248 11:18:43 INFO - PID 1659 | 11:18:46 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:18:46 INFO - PID 1659 | RSS: Main: 290402304 11:18:46 INFO - PID 1659 | 11:18:49 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:18:50 INFO - PID 1659 | RSS: Main: 290160640 11:18:50 INFO - PID 1659 | 11:18:53 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:18:53 INFO - PID 1659 | RSS: Main: 290156544 11:18:53 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 11:18:55 INFO - PID 1659 | RSS: Main: 291864576 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | __start_tp_report 11:18:55 INFO - PID 1659 | _x_x_mozilla_page_load 11:18:55 INFO - PID 1659 | _x_x_mozilla_page_load_details 11:18:55 INFO - PID 1659 | |i|pagename|runs| 11:18:55 INFO - PID 1659 | |0;ai-astar;100;116;114;114;114;113;114;114;115;112 11:18:55 INFO - PID 1659 | |1;audio-beat-detection;141;107;109;148;109;112;111;116;140;93 11:18:55 INFO - PID 1659 | |2;audio-dft;227;227;204;224;217;215;200;253;278;200 11:18:55 INFO - PID 1659 | |3;audio-fft;80;57;58;86;63;79;55;64;80;84 11:18:55 INFO - PID 1659 | |4;audio-oscillator;83;69;88;57;70;82;82;69;95;114 11:18:55 INFO - PID 1659 | |5;imaging-gaussian-blur;88;89;88;87;88;98;95;88;89;89 11:18:55 INFO - PID 1659 | |6;imaging-darkroom;100;98;96;103;100;97;97;96;99;99 11:18:55 INFO - PID 1659 | |7;imaging-desaturate;93;94;94;91;93;92;91;92;91;90 11:18:55 INFO - PID 1659 | |8;json-parse-financial;49;50;51;53;51;52;50;53;52;50 11:18:55 INFO - PID 1659 | |9;json-stringify-tinderbox;55;55;64;54;62;60;56;54;57;55 11:18:55 INFO - PID 1659 | |10;stanford-crypto-aes;85;88;90;89;86;87;88;89;133;93 11:18:55 INFO - PID 1659 | |11;stanford-crypto-ccm;139;138;135;138;140;138;138;141;140;147 11:18:55 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;187;191;184;231;183;184;181;180;184;186 11:18:55 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;69;69;64;65;68;65;71;67;67;69 11:18:55 INFO - PID 1659 | __end_tp_report 11:18:55 INFO - PID 1659 | __start_cc_report 11:18:55 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1208 11:18:55 INFO - PID 1659 | __end_cc_report 11:18:55 INFO - PID 1659 | __startTimestamp1496168335863__endTimestamp 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | ------- Summary: start ------- 11:18:55 INFO - PID 1659 | Number of tests: 14 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:4.55 (4.0%) stddev-sans-first:1.12 11:18:55 INFO - PID 1659 | Values: 100.0 116.0 114.0 114.0 114.0 113.0 114.0 114.0 115.0 112.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:118.60 Median:128.00 stddev:17.97 (14.0%) stddev-sans-first:17.14 11:18:55 INFO - PID 1659 | Values: 141.0 107.0 109.0 148.0 109.0 112.0 111.0 116.0 140.0 93.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:224.50 Median:225.50 stddev:24.62 (10.9%) stddev-sans-first:26.09 11:18:55 INFO - PID 1659 | Values: 227.0 227.0 204.0 224.0 217.0 215.0 200.0 253.0 278.0 200.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:70.60 Median:79.50 stddev:12.26 (15.4%) stddev-sans-first:12.52 11:18:55 INFO - PID 1659 | Values: 80.0 57.0 58.0 86.0 63.0 79.0 55.0 64.0 80.0 84.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:82.00 stddev:16.07 (19.6%) stddev-sans-first:17.03 11:18:55 INFO - PID 1659 | Values: 83.0 69.0 88.0 57.0 70.0 82.0 82.0 69.0 95.0 114.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:89.00 stddev:3.60 (4.0%) stddev-sans-first:3.76 11:18:55 INFO - PID 1659 | Values: 88.0 89.0 88.0 87.0 88.0 98.0 95.0 88.0 89.0 89.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:98.50 Median:97.00 stddev:2.17 (2.2%) stddev-sans-first:2.24 11:18:55 INFO - PID 1659 | Values: 100.0 98.0 96.0 103.0 100.0 97.0 97.0 96.0 99.0 99.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.50 stddev:1.37 (1.5%) stddev-sans-first:1.41 11:18:55 INFO - PID 1659 | Values: 93.0 94.0 94.0 91.0 93.0 92.0 91.0 92.0 91.0 90.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.37 (2.7%) stddev-sans-first:1.22 11:18:55 INFO - PID 1659 | Values: 49.0 50.0 51.0 53.0 51.0 52.0 50.0 53.0 52.0 50.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:57.20 Median:56.50 stddev:3.55 (6.3%) stddev-sans-first:3.68 11:18:55 INFO - PID 1659 | Values: 55.0 55.0 64.0 54.0 62.0 60.0 56.0 54.0 57.0 55.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:88.50 stddev:14.30 (16.2%) stddev-sans-first:14.88 11:18:55 INFO - PID 1659 | Values: 85.0 88.0 90.0 89.0 86.0 87.0 88.0 89.0 133.0 93.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:139.50 stddev:3.13 (2.2%) stddev-sans-first:3.32 11:18:55 INFO - PID 1659 | Values: 139.0 138.0 135.0 138.0 140.0 138.0 138.0 141.0 140.0 147.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.10 Median:185.00 stddev:15.04 (8.1%) stddev-sans-first:15.94 11:18:55 INFO - PID 1659 | Values: 187.0 191.0 184.0 231.0 183.0 184.0 181.0 180.0 184.0 186.0 11:18:55 INFO - PID 1659 | 11:18:55 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.50 stddev:2.22 (3.2%) stddev-sans-first:2.28 11:18:55 INFO - PID 1659 | Values: 69.0 69.0 64.0 65.0 68.0 65.0 71.0 67.0 67.0 69.0 11:18:55 INFO - PID 1659 | -------- Summary: end -------- 11:18:55 INFO - PID 1659 | 11:18:56 INFO - TEST-INFO | 1659: exit 0 11:18:56 INFO - TEST-OK | kraken | took 90199ms 11:18:56 INFO - SUITE-END | took 1233s 11:18:57 INFO - Completed test suite (00:20:34) 11:18:57 INFO - javascript benchmark 11:18:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7858.784943250551, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3591.084273510255, 3618.819059047047, 3635.5455568053994, 3653.2145588698427, 3656.689181419579, 4424.690271680984, 5430.647007944481, 5678.48893599576, 6222.128901953719, 6267.592606480575, 6198.907016394758, 6213.471854892328, 6219.9689001554925, 6274.152989346437, 6298.086777417272, 5454.754536045868, 5483.561315094803, 5495.175723641446, 5508.614396992212, 5523.558115350762, 5587.106063029916, 5670.461306175906, 5678.914816277759, 5696.946064977971, 5723.713814309289, 4304.375865499485, 5342.732863356817, 5444.700541470217, 5544.417836127229, 5669.801556945507, 5508.421615730331, 5528.585356098269, 5552.7778888844805, 5590.972045139789, 5591.021571225019, 4547.840825571089, 5504.174373843915, 5520.779168833242, 5529.806456773994, 5555.722213889289, 5488.615796894219, 6199.163112979735, 6316.810495685137, 6318.115463835067, 6330.715117819709, 5792.218050563204, 6550.7707230246715, 6619.172603424572, 6663.06717059612, 6767.458603311772, 6819.147606549182, 6843.768121738085, 6865.897011544831, 6870.5534140281225, 6875.5874647521305, 5962.135490353918, 6325.715342809584, 6326.873462530723, 6332.018537126753, 6343.365663433619, 6754.662266886636, 6890.10428644273, 6941.0629565009085, 6989.860202795916, 7037.049998250171, 6456.9354306456335, 6503.674816259168, 6510.08858759774, 6579.605223686498, 6641.933580664131, 5763.7982670606325, 6774.220964589135, 6854.383105520527, 6857.965710171417, 6874.2094659113845, 6693.0, 6734.6632668367365, 6741.629210393476, 6761.357671021246, 6765.695543700447, 5625.268715067097, 6007.88854062, 6548.476121910236, 6612.966935165294, 6676.86646267072, 5401.270828438111, 5402.216678581663, 5424.0, 5426.267453893753, 5442.12925931849, 4802.687825291345, 4806.927896081562, 4809.278608208727, 4813.614910807127, 4861.538153875376, 5273.446288139767, 6143.757528502154, 6363.268224154282, 6436.645984470899, 6438.291787903327, 6924.099867017256, 6927.896081558781, 6935.237123916365, 6938.930610693929, 6940.30596940312, 6429.7428102875365, 6459.386358295955, 6461.547691661539, 6466.773662921775, 6470.0618410330235, 5192.935448233103, 5913.0, 5987.6108052976415, 6084.817455476343, 6098.756049758049, 7031.40233080194, 7045.471589630901, 7054.047703559953, 7068.929310706827, 7080.575165490087, 6790.185177778698, 6830.45356371499, 6872.278410766798, 6884.51808373409, 6887.311268873073, 6802.115724955922, 6890.44876409896, 6920.9307906920285, 7028.332308430691, 7072.504924655224, 6682.2649508555, 6758.425533829482, 6768.120144381199, 6788.76239331621, 6795.694193761194, 6813.1142951415995, 6861.622610756456, 6901.482388820959, 6908.3091690830515, 6919.481038922, 6450.032495125769, 6520.9347906520325, 6568.6058836469965, 6641.734330626721, 6656.068150458843, 6667.399934005963, 6670.83322916931, 6673.699683514156, 6696.732130714717, 6704.497162712719, 5601.907628012498, 6474.546781725234, 6552.246491653521, 6574.243961944438, 6595.87870062081, 6195.411435913581, 6234.501239900887, 6473.158489396348, 6646.401823835878, 6714.83212919681, 5349.518543331119, 6477.546571739933, 6491.837704057436, 6511.772087976898, 6521.413072823469], "value": 6133.831977304988, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.3777607921306, 776.4797585617636, 845.8942632170977, 1237.7029512916897, 1289.3810970734053, 33439.16402089949, 37679.81160094199, 39147.80426097869, 39174.60825391742, 39205.80397098011, 39082.0, 40719.18561628766, 40727.0, 40805.795971020176, 40872.97817554563, 7842.96078519608, 7992.3206527444945, 8170.959145204266, 8381.078081411071, 8391.11893251209, 8334.874976875351, 8457.365697572675, 8476.110008449117, 8477.915220847804, 8487.321014318839, 8404.369672274574, 8430.83138337233, 8446.0, 8448.74653760385, 8491.32069434447, 8431.19903609156, 8460.900082989203, 8464.280536154372, 8472.78818029548, 8473.745787626382, 8131.227533384319, 8179.100298967109, 8241.340692744627, 8242.917570824275, 8256.967879015123, 36893.31066893316, 36923.0, 37020.44469332962, 37075.443868341994, 37113.25773484542, 3480.791152530831, 3489.1626009757474, 3521.4893840393256, 3522.559680039995, 3526.2594855080465, 30776.768929242804, 30817.845910770528, 30825.537616935762, 30832.0, 30840.920567780246, 3088.058142266608, 3119.4229067622564, 3120.0327898351584, 3138.7489000879873, 3147.6380216275193, 234344.0, 238576.0, 263723.3627663718, 299142.0, 300432.0, 46929.0, 47187.528124719, 47535.76232118817, 47597.0, 47636.0, 48654.51345486571, 49071.01857962821, 50077.49922500801, 50095.74952125289, 50260.49739502558, 14052.227127508086, 14144.656257655506, 14291.857081429052, 14292.999490035805, 14317.85682143186, 11264.54941802335, 11421.543138274375, 11444.656660300205, 11466.94266528662, 11505.252158609663, 13845.100068495716, 13930.024898257227, 13931.512397066053, 13975.0, 14042.789358159636, 6210.844728881814, 6221.440070393686, 6248.937510624835, 6262.499000079983, 6264.467520260829, 967.9322447428613, 974.7758015656296, 1005.3465247588979, 1035.0425856083243, 1039.4387031003325, 23779.524409511712, 24678.629820552705, 24790.132345367827, 24826.255212343298, 25148.0, 1113.7883802077579, 1114.437209209344, 1134.597217987619, 1149.8850114988602, 1164.5691094295164, 12485.25088494675, 12598.937005314914, 12626.305553194663, 12641.810372844415, 12736.36318184087, 280098.0, 335660.64339356293, 336059.0, 337493.2501349959, 339104.0, 54898.45101549013, 55161.448385516436, 55214.447855521736, 55222.723886380314, 55311.723441382535, 1469.8530146985215, 1494.2230040378943, 1514.48507507448, 1522.3301747231183, 1524.6798172383924, 23904.40238994039, 24059.398515037265, 24083.036678533364, 24120.87939560291, 24139.879300603385, 32852.50721239231, 38090.23819523538, 38325.233495330496, 38627.2274554513, 38629.0, 279291.0, 281596.0, 281874.0, 282149.0, 282318.0, 33718.8314058438, 35441.46837797383, 35563.933082007585, 35858.10354741153, 36115.0, 42799.5720042808, 44927.775361122985, 45044.09911801811, 45054.77472612747, 45108.548914511746, 29048.0, 30222.697773021988, 30811.383772324876, 31456.370872581963, 31822.045338639877, 30752.384952301276, 30795.846020769754, 31242.906498272452, 31522.0, 32203.51694724628, 36057.639423605426, 36209.27581448409, 37346.253074937806, 37650.0, 37803.810980944916, 31091.37817243688, 31623.0, 31681.5247771279, 32255.0, 32313.51529727009, 23179.768202317762, 24345.75654243435, 24462.0, 24493.265202043254, 24597.262082137564], "value": 17608.114952556894, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.6820080337338, 415.8648439257242, 424.70695220297983, 430.20412237360887, 440.9779511024448, 17486.03826789526, 20300.57895947284, 20995.58008839824, 21439.892800535996, 21480.785192148072, 21651.0, 21887.67168492474, 22487.437814054658, 22778.65832012517, 22846.885765571147, 5699.173619825123, 6000.639961602296, 6044.848878778022, 6063.4239747224165, 6080.574359794816, 5864.530837532987, 5966.40335966402, 5994.801039792037, 6045.455908968192, 6091.817245482643, 5896.88206235875, 5915.73379197935, 5940.61386009908, 5949.85125371867, 5950.256217972754, 4982.975085124587, 5700.344460387067, 5908.202392676976, 5917.615355001933, 5951.3751056139345, 5358.91961620576, 5371.462853714597, 5374.516293533601, 5374.946250537484, 5378.327709036371, 24845.751542484526, 27025.0003749307, 27633.309167270778, 27921.301967450774, 27952.161435156977, 2049.7745248022707, 2200.3508964855246, 2206.007296716468, 2211.513467037249, 2211.966820497694, 14499.42002319906, 18051.819481805145, 18074.548136296566, 18220.180091895898, 18447.354342597944, 2127.319257837493, 2145.6674215496623, 2164.7077644517944, 2166.068590506082, 2168.6638571021517, 151561.24219378832, 152239.23880380637, 152416.47583524135, 152787.47212527844, 152861.70707439398, 10608.0, 12684.492620295085, 12874.356282185854, 12918.22490650564, 13063.542776002985, 14717.0, 15387.076775393512, 15759.212039397984, 16617.83382166163, 16669.83330166707, 8590.1839325264, 8934.0, 9093.36346455755, 9132.452052876848, 9139.543022848964, 7019.473539484559, 8544.145585441405, 8565.357598180162, 8569.0, 8652.52411117382, 7811.296983271419, 7891.408144389194, 7916.7229146980735, 8060.637271322805, 8084.191580841986, 2195.9890200549216, 2199.450137465634, 2200.911963521441, 2208.9889550552143, 2242.4057624729458, 964.8118616869783, 987.8172538080478, 997.7156510394502, 1023.4166525080782, 1042.1089968077335, 20515.79484205139, 21162.894185528974, 21212.0, 21454.678179827315, 21471.46321341979, 900.4552245891273, 998.1465846700966, 1013.0426746724274, 1049.5644307612408, 1053.167997282154, 10516.3690178588, 10980.835287470694, 11005.559777608805, 11009.2843965142, 11026.117910567136, 521343.0, 525798.0, 527977.0, 528871.0, 536466.0, 128278.35860820823, 128923.35538322249, 129308.0, 135110.3244483791, 136599.0, 1148.7645032768262, 1160.4487868262509, 1208.510553225945, 1210.9757804843853, 1219.670688914006, 17557.82442175562, 21735.565288694455, 22534.0, 22740.317790466313, 22989.0, 56059.43940560542, 57868.13197802121, 57888.84222315614, 58332.0, 58416.53958651068, 57169.0, 59768.10347844699, 78878.6056069716, 78979.60510197413, 79970.0, 8711.041785403728, 8767.780805479913, 8873.91126088731, 9009.369344145847, 9152.130547597968, 10750.0, 13363.198208107544, 13940.30298485092, 13940.860591393955, 14364.784528231876, 10945.179111566506, 11070.059044981266, 11484.540618375506, 11941.880581194424, 12112.394380280775, 10921.399323037309, 10960.232783705063, 11035.39305338214, 11282.323060616389, 11387.259828111146, 9399.34204605671, 10083.0, 11362.14783891195, 12095.879041209475, 12141.453634586289, 8652.307815374881, 8754.781130471789, 8811.691590794548, 9108.225800807006, 9138.451692898447, 10075.0, 11267.141416058457, 11858.881411185777, 12145.939270303592, 12174.695632609255], "value": 10323.248802269964, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1194.498310709502, 1442.8557144285567, 1449.2536343782954, 1452.3972551391166, 1454.7236025155219, 11711.59009434669, 11897.405129743522, 12069.3965301735, 12153.906148446627, 12175.025997920167, 11933.224340417888, 12059.0, 12084.879151208484, 12103.21329113607, 12140.81788773167, 2515.1322793636214, 2545.2873195505194, 2553.0553695132826, 2558.9360265993314, 2560.7567281108263, 2495.800335973118, 2547.350425641459, 2548.0062775517563, 2549.8087643426725, 2550.3624093976505, 2507.260358194337, 2527.5071361084506, 2537.568613335737, 2540.5681034224226, 2552.591585346345, 2076.5950639625203, 2478.8512689238614, 2485.117783186961, 2490.439651078511, 2491.700995880497, 2377.619580867053, 2453.116877923946, 2467.728549859514, 2479.00839664134, 2485.825992180548, 11672.941635291769, 11736.647900562997, 11739.236949598335, 11747.118966077493, 11778.823317650242, 5937.317208521033, 6659.200895892475, 6666.300038495937, 6677.031830384615, 6677.332266773284, 4093.9590604093873, 4267.637250833683, 4342.413774140482, 4343.435353404037, 4348.673849461303, 1238.5479300055506, 1243.2664727810545, 1246.1992420951728, 1249.3628249592773, 1250.4435526190848, 15377.0, 15670.921645391814, 16287.185640718035, 16420.343186272534, 16535.090570018645, 4368.847090351823, 5266.131088370453, 5476.698781567052, 5683.658980461186, 5700.686462244616, 11778.764424711459, 11808.409579520989, 11813.76372472563, 11844.111691623162, 11847.407629618485, 5400.91898621521, 5562.97085039269, 6421.068945003042, 6454.483641308684, 6527.902081468686, 5958.612690175125, 6080.179175811298, 6123.2652081750475, 6149.815505534841, 6186.10301506288, 4148.647364974011, 4418.381426600278, 4427.380166776654, 4489.371487991683, 4520.59314661682, 1116.0513563470984, 1259.3136740476461, 1266.7466506698697, 1289.6517940156104, 1293.9870601294056, 766.9348105410991, 781.6091954022988, 809.7773112394138, 810.5784991804229, 815.6696537902159, 5991.4907232884825, 6114.571979961449, 6178.6601736904, 6265.373462653789, 6280.905786413207, 581.8865321262314, 585.096026638844, 588.7497813429276, 597.6384287506083, 611.9296280927662, 3815.980920095382, 3819.968608475696, 3856.305864944281, 3872.0707030312524, 4385.6710746694125, 14867.851321486864, 15294.617634559003, 15381.46164884246, 15403.537893863202, 15410.691786164438, 12223.93888030554, 14960.925195374171, 15257.6185595361, 15333.386664533517, 15423.997440166353, 8355.248027677539, 8481.448705834102, 8494.150584941457, 8511.361647876434, 8642.56787160652, 6241.407066328691, 6814.352636499525, 7140.107486564179, 7183.568985860865, 7193.136823581203, 13391.665208369868, 13425.462981480632, 13537.729245415234, 13599.728005440034, 13901.513447029305, 13993.300334983414, 14092.647683807987, 14108.576742697736, 14267.714645707236, 14297.499587514387, 9049.819003620023, 9379.812403752023, 9493.905060949579, 9497.952510237405, 9512.85730714026, 10619.256652034283, 10740.570377185139, 10762.51568679427, 10768.623098191525, 10775.946120269662, 8893.199612034949, 9124.27005839518, 9181.586828592595, 9214.170724634814, 9336.0, 8839.602217900081, 8945.15021072997, 8970.461772293684, 9046.321525885453, 9242.907570924204, 8159.551224682699, 8184.345252379914, 8203.220694034057, 8229.588520574067, 8249.463784853966, 5922.407759224043, 6033.5173186145885, 6043.184170137151, 6066.939330606638, 6203.627782333075, 6059.121427393092, 6127.754889804536, 6159.322474527708, 6233.75064997395, 6237.095621135], "value": 5302.708179337318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.7278681808417, 391.4148348219412, 391.528208508747, 393.2155350076596, 402.07923854373485, 5893.263342082239, 5936.940630593693, 6217.316095229527, 6227.221597300338, 6275.968620156898, 4948.901021979558, 5784.380373495428, 5918.763249470016, 6055.9697201514045, 6073.9066967945755, 4090.0, 4303.0, 4573.131105090039, 4584.404027476424, 4604.815807367701, 4564.771761411933, 4578.97710511447, 4600.930986035195, 4601.171789077965, 4609.193391156551, 3893.2213557288514, 4209.768462734563, 4274.760319507354, 4470.374147619336, 4575.405197324343, 4095.5904409559103, 4439.66702497314, 4452.97773511132, 4458.977705111453, 4466.508684044768, 3465.5148279240925, 3797.297499962488, 3798.2403519295976, 3818.968878402816, 3826.8660596879254, 5082.440931497569, 5782.392848750863, 5837.598976245713, 5979.940200597982, 6038.215032045806, 4155.480564929384, 4352.39066530686, 4446.244138496463, 4447.755373454491, 5303.284056652341, 5682.289713785777, 6410.910145275315, 6478.209658421654, 6595.208574970986, 6615.526848404578, 3462.3594634992605, 3835.520559930009, 4231.894202644928, 4324.891877703082, 4327.221100201962, 5912.793052243193, 6113.388661133851, 6590.242122155965, 6842.042114104079, 6970.477214208955, 3791.9431208531896, 4284.250256205151, 4321.459817522809, 4348.456442944632, 4358.760268185217, 3746.3069332173636, 4444.133393988206, 4453.487848897354, 4457.153140903218, 4473.172463094338, 5098.031374038921, 5226.3205783248695, 5226.817061402909, 5230.503102205284, 5263.6841789492755, 6678.0, 6690.297518760557, 6699.129113215251, 6702.731890724414, 6776.69504872282, 1233.506597361045, 1512.5613572064199, 1523.3145084711923, 1527.9388824446899, 1534.4552683797315, 853.5476197615274, 956.3592393096642, 997.3965750720854, 998.5485632771529, 1005.5073014222978, 6228.6574238416415, 6243.562950593506, 6253.312135665074, 6263.123162757218, 6267.2165979252595, 6117.418845209698, 6886.827829304208, 6909.585424874524, 6921.0, 6958.582485050812, 5217.895642087213, 5986.640801551921, 6173.69131543421, 6217.253929528395, 6244.46922011625, 3404.7105995990123, 3572.3034008368118, 3582.659647333499, 3584.8386822593047, 3599.0102721751728, 5756.136579513107, 5942.554308426886, 6998.4751143664425, 7014.92985070153, 7070.92929070713, 5651.745671444794, 6367.713452894631, 7004.579725216503, 7059.364657180776, 7111.502194846311, 6496.967515162489, 6792.45660347171, 6998.510104292753, 7055.753048643375, 7062.964685176541, 5398.433164517669, 6685.431738302299, 6839.418649414855, 6863.279355667583, 6877.3810357068105, 5434.402215756343, 6473.643949582818, 6544.443722283469, 6717.563358381885, 6777.2883847195335, 6649.202095748541, 6843.657817109025, 6936.7225310989015, 6957.138787972724, 7050.025992722046, 3724.3296206681975, 4366.410534577792, 4377.540358262337, 4381.978090109529, 4404.405405270376, 6591.868162636624, 6834.521583489108, 6840.0, 6893.448524117958, 6932.757353492805, 5054.281544274964, 6575.210974683068, 6605.669716514251, 6612.404883560503, 6613.570117942319, 5298.178782288752, 5845.883082338244, 6574.474042076718, 6617.316510324511, 6652.135222421054, 6448.871022579428, 6487.448566871869, 6493.448056915215, 6507.154069970874, 6515.381038801306, 6302.968485157545, 6608.834779130368, 6629.933700662931, 6642.837503436976, 6683.331666833278, 5431.5926305526455, 6050.425209605257, 6426.389492998158, 6468.256150542559, 6486.0919471275], "value": 4713.822290721089, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.5147687126158, 818.055146306016, 838.5597561280327, 848.7014867253101, 863.3611127765452, 16551.006939583618, 17074.146292685364, 17090.230939607718, 18780.248790048383, 19606.803931960676, 17873.82126178738, 18272.17775200116, 18352.724709129372, 18418.631627367446, 18442.262309507638, 964.2960638733746, 1004.6584161385127, 1005.4419797012655, 1006.8238058339762, 1008.2186305613135, 862.3489265604492, 988.9604415823394, 998.9450580218086, 1004.3120462483186, 1005.0150852164883, 1051.0698032241444, 1062.495314725503, 1065.6270305393127, 1067.8505009298703, 1067.9946600267026, 936.5083331251079, 983.2379905573202, 1059.3061544688242, 1060.8514807926897, 1061.87257529097, 767.3209209849268, 774.0363247756579, 775.9806004849868, 776.5961699916012, 776.953382797034, 14545.927270363687, 16774.49676509707, 17248.82751172485, 17642.823571764246, 17809.376671816422, 17582.736258956127, 17625.471235862944, 17838.732419013726, 17850.46448606531, 17882.642347152985, 14840.851591484055, 14911.552653420415, 14972.101673899491, 14975.925120374437, 14979.775303370348, 3536.734744894143, 3648.4892115103908, 3697.149655579232, 3701.5743189533277, 3702.2780557791243, 130296.0, 133396.66603334036, 133863.33068334693, 134980.32509837387, 136523.0, 12628.936855315664, 13007.414666340037, 13685.521006764715, 13774.449022039005, 13899.652508687364, 25420.364490887874, 26110.086146984766, 26241.868790655924, 26409.471810563682, 26482.0, 6742.831429214309, 7658.846823063507, 7805.492642978188, 7920.6039698014865, 7956.403269754792, 3945.64489195968, 3967.821448034845, 4030.717849750489, 4034.35450327952, 4048.3725022621543, 4313.417688611998, 4320.330348795942, 4359.06280149763, 4402.207602631493, 4415.977920110379, 853.3770347646129, 870.8737233101292, 876.7983363826355, 877.7147427086222, 880.2517859819103, 641.4355367276767, 649.8018104478085, 666.1972323350351, 666.6000399760202, 676.7090151235017, 10845.186611004206, 11494.023008044243, 11911.583094591648, 11999.880001199876, 12118.515259389702, 611.39471922797, 645.1967300710645, 657.7566300468857, 667.9398854103154, 674.275154209227, 4917.704937703749, 5206.140986737221, 5387.676739395649, 5448.509634132947, 5465.316835395575, 125191.0, 125852.0, 127001.0, 127381.36309318579, 128350.35824820815, 100279.0, 107840.38239426415, 108790.0, 109133.0, 112267.43866280616, 95358.56962145574, 95654.56518152234, 97631.51184244176, 97774.73365296541, 97946.5102674482, 9433.43399396011, 10304.227182961458, 10574.41840698769, 10824.783504330027, 10925.39910304909, 30993.0, 40138.0, 40360.3945940817, 40804.0, 41051.384229237185, 54144.72927635337, 54552.45447545474, 54696.0, 54969.45030549643, 55198.17202741882, 13512.256825874278, 13641.181529108286, 13939.372728227458, 13993.860061399662, 13994.0, 15343.00270482415, 15538.844611553739, 15930.761038584662, 16034.438794642132, 16057.518274451319, 13274.0, 14160.362783674966, 14262.0, 16903.323867045674, 18395.080245987916, 16105.275262612977, 16289.429869954494, 17285.308587656353, 17959.820401796336, 18162.09189540544, 16979.0, 17013.149342532575, 17672.29310827604, 17694.0, 17820.643587127925, 10260.076593106656, 11720.824187637021, 11785.764284714429, 12185.14703970749, 12389.75220495603, 11618.477168527606, 12924.806127907901, 13584.0, 13612.319384030958, 13658.453661853415], "value": 8452.18487940099, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 116.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 115.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 107.0, 109.0, 148.0, 109.0, 112.0, 111.0, 116.0, 140.0, 93.0], "value": 118.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 227.0, 204.0, 224.0, 217.0, 215.0, 200.0, 253.0, 278.0, 200.0], "value": 224.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 58.0, 86.0, 63.0, 79.0, 55.0, 64.0, 80.0, 84.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 88.0, 57.0, 70.0, 82.0, 82.0, 69.0, 95.0, 114.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 87.0, 88.0, 98.0, 95.0, 88.0, 89.0, 89.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 98.0, 96.0, 103.0, 100.0, 97.0, 97.0, 96.0, 99.0, 99.0], "value": 98.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 91.0, 93.0, 92.0, 91.0, 92.0, 91.0, 90.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 53.0, 51.0, 52.0, 50.0, 53.0, 52.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 64.0, 54.0, 62.0, 60.0, 56.0, 54.0, 57.0, 55.0], "value": 57.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 90.0, 89.0, 86.0, 87.0, 88.0, 89.0, 133.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 135.0, 138.0, 140.0, 138.0, 138.0, 141.0, 140.0, 147.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 191.0, 184.0, 231.0, 183.0, 184.0, 181.0, 180.0, 184.0, 186.0], "value": 189.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 64.0, 65.0, 68.0, 65.0, 71.0, 67.0, 67.0, 69.0], "value": 67.4, "unit": "score"}]}]} 11:18:57 INFO - Return code: 0 11:18:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:18:57 INFO - # TBPL SUCCESS # 11:18:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:18:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:18:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:18:57 INFO - 2017-05-30 11:18:57.197 screenresolution[1667:11103] starting screenresolution argv=screenresolution get 11:18:57 INFO - 2017-05-30 11:18:57.200 screenresolution[1667:11103] Display 0: 1600x1200x32@60 11:18:57 INFO - 2017-05-30 11:18:57.205 screenresolution[1668:11106] starting screenresolution argv=screenresolution list 11:18:57 INFO - Available Modes on Display 0 11:18:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:18:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:18:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:18:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:18:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:18:57 INFO - Intel Iris: 11:18:57 INFO - Chipset Model: Intel Iris 11:18:57 INFO - Type: GPU 11:18:57 INFO - Bus: Built-In 11:18:57 INFO - VRAM (Dynamic, Max): 1536 MB 11:18:57 INFO - Vendor: Intel (0x8086) 11:18:57 INFO - Device ID: 0x0a2e 11:18:57 INFO - Revision ID: 0x0009 11:18:57 INFO - Displays: 11:18:57 INFO - Display: 11:18:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:18:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:18:57 INFO - Display Serial Number: 1600x1200 60 11:18:57 INFO - Main Display: Yes 11:18:57 INFO - Mirror: Off 11:18:57 INFO - Online: Yes 11:18:57 INFO - Rotation: Supported 11:18:57 INFO - Return code: 0 11:18:57 INFO - Running post-action listener: _resource_record_post_action 11:18:57 INFO - [mozharness: 2017-05-30 18:18:57.440944Z] Finished run-tests step (success) 11:18:57 INFO - Running post-run listener: _resource_record_post_run 11:18:57 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 51260416; Write bytes: 571547648; Read time: 573; Write time: 1842 11:18:57 INFO - TinderboxPrint: CPU usage
53.3% 11:18:57 INFO - TinderboxPrint: I/O read bytes / time
51,260,416 / 573 11:18:57 INFO - TinderboxPrint: I/O write bytes / time
571,547,648 / 1,842 11:18:57 INFO - TinderboxPrint: CPU idle
2,348.9 (46.7%) 11:18:57 INFO - TinderboxPrint: CPU system
1,014.2 (20.2%) 11:18:57 INFO - TinderboxPrint: CPU user
1,662.5 (33.1%) 11:18:57 INFO - TinderboxPrint: Swap in / out
287,657,984 / 0 11:18:57 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226429440; Write bytes: 221710336; Read time: 16780; Write time: 413 11:18:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:18:57 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 50199552; Write bytes: 321509376; Read time: 554; Write time: 1362 11:18:57 INFO - Running post-run listener: _upload_blobber_files 11:18:57 INFO - Blob upload gear active. 11:18:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:18:57 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 11:18:57 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'] 11:18:57 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 11:18:58 INFO - (blobuploader) - INFO - Open directory for files ... 11:18:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:18:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:18:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:18:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:18:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:18:59 INFO - (blobuploader) - INFO - Done attempting. 11:18:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:18:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:18:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:19:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:19:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:19:00 INFO - (blobuploader) - INFO - Done attempting. 11:19:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:19:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:19:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:19:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:19:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:19:01 INFO - (blobuploader) - INFO - Done attempting. 11:19:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:19:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:19:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:19:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:19:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:19:03 INFO - (blobuploader) - INFO - Done attempting. 11:19:03 INFO - (blobuploader) - INFO - Iteration through files over. 11:19:03 INFO - Return code: 0 11:19:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:19:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:19:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1be2f577c18c0a051289b6e332908944778f312b5216ea5f4e17236c1c677c25ec7156dbedc0817f28158cd7225fed234b6f1c01c152ea0011160e684c30f31d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26c2ac9124ca9226ce8e99dc7c4084fdb2006251f6f98e129b68d88559002ddc53ab525cded5df447d1491bbef4c448f3ef463e03ffcadbd1f92c98f5dcd343c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccbc312f27b746d6dd4fd9346bd95804c2b10f617fbf6536f348c659fd6b1b77e7d3cf12974ed3da91a8f38a4f66f42d4e72aeaa0891f34d670c4684491dd62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59356fde1de11b6e9caf969c28fa0c2c5e2e80a668640c839c5c592206138b51e1e616767b285f53036dbc71e1301f9285fc30feeb0cd157ee12901bbd33da2f"} 11:19:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:19:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:19:03 INFO - Contents: 11:19:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1be2f577c18c0a051289b6e332908944778f312b5216ea5f4e17236c1c677c25ec7156dbedc0817f28158cd7225fed234b6f1c01c152ea0011160e684c30f31d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26c2ac9124ca9226ce8e99dc7c4084fdb2006251f6f98e129b68d88559002ddc53ab525cded5df447d1491bbef4c448f3ef463e03ffcadbd1f92c98f5dcd343c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccbc312f27b746d6dd4fd9346bd95804c2b10f617fbf6536f348c659fd6b1b77e7d3cf12974ed3da91a8f38a4f66f42d4e72aeaa0891f34d670c4684491dd62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59356fde1de11b6e9caf969c28fa0c2c5e2e80a668640c839c5c592206138b51e1e616767b285f53036dbc71e1301f9285fc30feeb0cd157ee12901bbd33da2f"} 11:19:03 INFO - Running post-run listener: copy_logs_to_upload_dir 11:19:03 INFO - Copying logs to upload dir... 11:19:03 INFO - mkdir: /builds/slave/test/build/upload/logs 11:19:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.811927 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 11:19:03.939533) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:19:03.942919) ========= 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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/1be2f577c18c0a051289b6e332908944778f312b5216ea5f4e17236c1c677c25ec7156dbedc0817f28158cd7225fed234b6f1c01c152ea0011160e684c30f31d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26c2ac9124ca9226ce8e99dc7c4084fdb2006251f6f98e129b68d88559002ddc53ab525cded5df447d1491bbef4c448f3ef463e03ffcadbd1f92c98f5dcd343c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccbc312f27b746d6dd4fd9346bd95804c2b10f617fbf6536f348c659fd6b1b77e7d3cf12974ed3da91a8f38a4f66f42d4e72aeaa0891f34d670c4684491dd62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59356fde1de11b6e9caf969c28fa0c2c5e2e80a668640c839c5c592206138b51e1e616767b285f53036dbc71e1301f9285fc30feeb0cd157ee12901bbd33da2f"} build_url:https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010770 build_url: 'https://queue.taskcluster.net/v1/task/MnP_BPRBT1qJkS--yD_oYg/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/1be2f577c18c0a051289b6e332908944778f312b5216ea5f4e17236c1c677c25ec7156dbedc0817f28158cd7225fed234b6f1c01c152ea0011160e684c30f31d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26c2ac9124ca9226ce8e99dc7c4084fdb2006251f6f98e129b68d88559002ddc53ab525cded5df447d1491bbef4c448f3ef463e03ffcadbd1f92c98f5dcd343c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccbc312f27b746d6dd4fd9346bd95804c2b10f617fbf6536f348c659fd6b1b77e7d3cf12974ed3da91a8f38a4f66f42d4e72aeaa0891f34d670c4684491dd62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59356fde1de11b6e9caf969c28fa0c2c5e2e80a668640c839c5c592206138b51e1e616767b285f53036dbc71e1301f9285fc30feeb0cd157ee12901bbd33da2f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:19:03.977569) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:19:03.977931) ========= 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.eDfLSt6eyH/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.5pE2aDO0dW/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005200 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:19:04.026660) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:19:04.026977) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:19:04.027347) ========= ========= Total master_lag: 0.56 =========