builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0055 starttime: 1495554588.87 results: success (0) revision: 3a82a745123f1f0f36937a45b877724023d112cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:48.868078) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:48.868561) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:48.869045) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:48.894021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:48.894436) ========= 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.xpeDpOny2o/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.Yknp0kXTqO/Listeners TMPDIR=/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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-23 08:49:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 57.5M=0s 2017-05-23 08:49:49 (57.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.142376 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:49.059352) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:49.059673) ========= 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.xpeDpOny2o/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.Yknp0kXTqO/Listeners TMPDIR=/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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.035558 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:49.116324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 08:49:49.116830) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3a82a745123f1f0f36937a45b877724023d112cd --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 3a82a745123f1f0f36937a45b877724023d112cd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xpeDpOny2o/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.Yknp0kXTqO/Listeners TMPDIR=/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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-23 08:49:49,226 truncating revision to first 12 chars 2017-05-23 08:49:49,226 Setting DEBUG logging. 2017-05-23 08:49:49,226 attempt 1/10 2017-05-23 08:49:49,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3a82a745123f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 08:49:50,288 unpacking tar archive at: mozilla-central-3a82a745123f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.346896 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 08:49:50.485287) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:50.485607) ========= script_repo_revision: 3a82a745123f1f0f36937a45b877724023d112cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:50.486015) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:50.486312) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:49:50.505075) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-05-23 08:49:50.505463) ========= /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.xpeDpOny2o/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.Yknp0kXTqO/Listeners TMPDIR=/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:49:50 INFO - MultiFileLogger online at 20170523 08:49:50 in /builds/slave/test 08:49:50 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 08:49:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:49:50 INFO - {'append_to_log': False, 08:49:50 INFO - 'base_work_dir': '/builds/slave/test', 08:49:50 INFO - 'blob_upload_branch': 'Firefox', 08:49:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:49:50 INFO - 'branch': 'Firefox', 08:49:50 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:50 INFO - 'config_files': ('talos/mac_config.py',), 08:49:50 INFO - 'default_actions': ('clobber', 08:49:50 INFO - 'read-buildbot-config', 08:49:50 INFO - 'download-and-extract', 08:49:50 INFO - 'populate-webroot', 08:49:50 INFO - 'create-virtualenv', 08:49:50 INFO - 'install', 08:49:50 INFO - 'run-tests'), 08:49:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:50 INFO - 'download_minidump_stackwalk': True, 08:49:50 INFO - 'download_symbols': 'ondemand', 08:49:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:49:50 INFO - 'tooltool.py': '/tools/tooltool.py', 08:49:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:49:50 INFO - '/tools/misc-python/virtualenv.py')}, 08:49:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:50 INFO - 'gecko_profile': False, 08:49:50 INFO - 'gecko_profile_interval': 0, 08:49:50 INFO - 'installer_path': 'installer.exe', 08:49:50 INFO - 'log_level': 'info', 08:49:50 INFO - 'log_name': 'talos', 08:49:50 INFO - 'log_to_console': True, 08:49:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:49:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:49:50 INFO - 'opt_config_files': (), 08:49:50 INFO - 'pip_index': False, 08:49:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:50 INFO - 'cmd': ('bash', 08:49:50 INFO - '-c', 08:49:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:50 INFO - 'enabled': True, 08:49:50 INFO - 'halt_on_failure': False, 08:49:50 INFO - 'name': 'check_screen_resolution'},), 08:49:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:50 INFO - 'cmd': ('bash', 08:49:50 INFO - '-c', 08:49:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:50 INFO - 'enabled': True, 08:49:50 INFO - 'halt_on_failure': False, 08:49:50 INFO - 'name': 'check_screen_resolution'},), 08:49:50 INFO - 'run_cmd_checks_enabled': True, 08:49:50 INFO - 'suite': 'dromaeojs-e10s', 08:49:50 INFO - 'system_bits': '32', 08:49:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:50 INFO - 'title': 't-yosemite-r7-0055', 08:49:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:50 INFO - 'use_talos_json': True, 08:49:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:49:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:50 INFO - 'work_dir': 'build'} 08:49:50 INFO - [mozharness: 2017-05-23 15:49:50.716743Z] Running clobber step. 08:49:50 INFO - Running pre-action listener: _resource_record_pre_action 08:49:50 INFO - Running main action method: clobber 08:49:50 INFO - rmtree: /builds/slave/test/build 08:49:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:49:53 INFO - Running post-action listener: _resource_record_post_action 08:49:53 INFO - [mozharness: 2017-05-23 15:49:53.257642Z] Finished clobber step (success) 08:49:53 INFO - [mozharness: 2017-05-23 15:49:53.257764Z] Running read-buildbot-config step. 08:49:53 INFO - Running pre-action listener: _resource_record_pre_action 08:49:53 INFO - Running main action method: read_buildbot_config 08:49:53 INFO - Using buildbot properties: 08:49:53 INFO - { 08:49:53 INFO - "project": "", 08:49:53 INFO - "product": "firefox", 08:49:53 INFO - "who": "ryanvm@gmail.com", 08:49:53 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 08:49:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:49:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:49:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:49:53 INFO - "stage_platform": "macosx64", 08:49:53 INFO - "basedir": "/builds/slave/test", 08:49:53 INFO - "buildnumber": 31, 08:49:53 INFO - "platform": "yosemite_r7", 08:49:53 INFO - "parent_task_id": "SNiUiiIxRjiYwyshjir9fw", 08:49:53 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:49:53 INFO - "slavebuilddir": "test", 08:49:53 INFO - "taskId": "GwE4X_uFS_Ogb2Xxo7h6SA", 08:49:53 INFO - "branch": "mozilla-central", 08:49:53 INFO - "script_repo_revision": "production", 08:49:53 INFO - "revision": "3a82a745123f1f0f36937a45b877724023d112cd", 08:49:53 INFO - "slavename": "t-yosemite-r7-0055", 08:49:53 INFO - "repo_path": "mozilla-central" 08:49:53 INFO - } 08:49:53 INFO - Finding installer, test and symbols from parent task. 08:49:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GwE4X_uFS_Ogb2Xxo7h6SA'}, attempt #1 08:49:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GwE4X_uFS_Ogb2Xxo7h6SA'}, attempt #1 08:49:54 INFO - Task dependencies: eCVeuhBMSnmEB_cX-DYk3w SNiUiiIxRjiYwyshjir9fw 08:49:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw'}, attempt #1 08:49:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:49:54 INFO - Set test_url: https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/test_packages.json 08:49:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/target.crashreporter-symbols.zip 08:49:54 INFO - Running post-action listener: _resource_record_post_action 08:49:54 INFO - [mozharness: 2017-05-23 15:49:54.979597Z] Finished read-buildbot-config step (success) 08:49:54 INFO - [mozharness: 2017-05-23 15:49:54.979706Z] Running download-and-extract step. 08:49:54 INFO - Running pre-action listener: _resource_record_pre_action 08:49:54 INFO - Running main action method: download_and_extract 08:49:54 INFO - mkdir: /builds/slave/test/build/tests 08:49:54 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')]} 08:49:54 INFO - trying https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:49:54 INFO - Downloading https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/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 08:49:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/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 08:49:56 INFO - Downloaded 1328 bytes. 08:49:56 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:49:56 INFO - Using the following test package requirements: 08:49:56 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:49:56 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:49:56 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:49:56 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:49:56 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'jsshell-mac.zip'], 08:49:56 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:49:56 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:49:56 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:49:56 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:49:56 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 08:49:56 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:49:56 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:49:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:49:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:49:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:49:56 INFO - Fetch https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:50:00 INFO - Content-Length response header: 57624422 08:50:00 INFO - Bytes received: 57624422 08:50:18 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 08:50:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:50:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:50:18 INFO - Fetch https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:50:21 INFO - Content-Length response header: 57624422 08:50:21 INFO - Bytes received: 57624422 08:50:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:50:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:50:39 INFO - Fetch https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:50:47 INFO - Content-Length response header: 14410000 08:50:47 INFO - Bytes received: 14410000 08:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:47 INFO - trying https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:50:47 INFO - Downloading https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:50:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/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 08:50:51 INFO - Downloaded 67989937 bytes. 08:50:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:50:51 INFO - mkdir: /builds/slave/test/properties 08:50:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:50:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:50:51 INFO - Contents: 08:50:51 INFO - build_url:https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:50:51 INFO - Running post-action listener: _resource_record_post_action 08:50:51 INFO - Running post-action listener: set_extra_try_arguments 08:50:51 INFO - [mozharness: 2017-05-23 15:50:51.323917Z] Finished download-and-extract step (success) 08:50:51 INFO - [mozharness: 2017-05-23 15:50:51.324042Z] Running populate-webroot step. 08:50:51 INFO - Running pre-action listener: _resource_record_pre_action 08:50:51 INFO - Running main action method: populate_webroot 08:50:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:50:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:50:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:50:51 INFO - '--tppagecycles', 08:50:51 INFO - '7'], 08:50:51 INFO - 'tests': ['tsvgm']}}, 08:50:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:50:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:50:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:50:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:50:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['damp', 'tps']}, 08:50:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'tests': ['damp', 'tps']}, 08:50:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['dromaeo_dom']}, 08:50:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:50:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:50:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:50:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'tests': ['ts_paint', 'tp5o']}, 08:50:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['a11yr', 08:50:51 INFO - 'ts_paint', 08:50:51 INFO - 'tpaint', 08:50:51 INFO - 'sessionrestore', 08:50:51 INFO - 'sessionrestore_no_auto_restore', 08:50:51 INFO - 'tabpaint']}, 08:50:51 INFO - 'other-e10s': {'tests': ['a11yr', 08:50:51 INFO - 'ts_paint', 08:50:51 INFO - 'tpaint', 08:50:51 INFO - 'sessionrestore', 08:50:51 INFO - 'sessionrestore_no_auto_restore', 08:50:51 INFO - 'tabpaint']}, 08:50:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:50:51 INFO - 'ts_paint', 08:50:51 INFO - 'tpaint', 08:50:51 INFO - 'sessionrestore', 08:50:51 INFO - 'sessionrestore_no_auto_restore', 08:50:51 INFO - 'tabpaint']}, 08:50:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:50:51 INFO - 'ts_paint', 08:50:51 INFO - 'tpaint', 08:50:51 INFO - 'sessionrestore', 08:50:51 INFO - 'sessionrestore_no_auto_restore', 08:50:51 INFO - 'tabpaint']}, 08:50:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['a11yr', 08:50:51 INFO - 'ts_paint', 08:50:51 INFO - 'tpaint', 08:50:51 INFO - 'sessionrestore', 08:50:51 INFO - 'sessionrestore_no_auto_restore', 08:50:51 INFO - 'tabpaint']}, 08:50:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['a11yr', 08:50:51 INFO - 'ts_paint', 08:50:51 INFO - 'tpaint', 08:50:51 INFO - 'sessionrestore', 08:50:51 INFO - 'sessionrestore_no_auto_restore', 08:50:51 INFO - 'tabpaint']}, 08:50:51 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:50:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:50:51 INFO - 'bloom_basic_ref']}, 08:50:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['tsvgx', 08:50:51 INFO - 'tsvgr_opacity', 08:50:51 INFO - 'tart', 08:50:51 INFO - 'tscrollx', 08:50:51 INFO - 'cart', 08:50:51 INFO - 'tsvg_static']}, 08:50:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:50:51 INFO - 'tsvgr_opacity', 08:50:51 INFO - 'tart', 08:50:51 INFO - 'tscrollx', 08:50:51 INFO - 'cart', 08:50:51 INFO - 'tsvg_static']}, 08:50:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'talos_options': ['--disable-e10s'], 08:50:51 INFO - 'tests': ['tp5o']}, 08:50:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:50:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'talos_options': ['--disable-e10s', 08:50:51 INFO - '--xperf_path', 08:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:51 INFO - 'tests': ['tp5n']}, 08:50:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:50:51 INFO - 'talos_options': ['--xperf_path', 08:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:51 INFO - 'tests': ['tp5n']}}, 08:50:51 INFO - 'talos.zip': {'path': '', 08:50:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:50:51 INFO - Running post-action listener: _resource_record_post_action 08:50:51 INFO - [mozharness: 2017-05-23 15:50:51.345473Z] Finished populate-webroot step (success) 08:50:51 INFO - [mozharness: 2017-05-23 15:50:51.345614Z] Running create-virtualenv step. 08:50:51 INFO - Running pre-action listener: _resource_record_pre_action 08:50:51 INFO - Running main action method: create_virtualenv 08:50:51 INFO - Creating virtualenv /builds/slave/test/build/venv 08:50:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:50:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:50:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:50:51 INFO - Using real prefix '/tools/python27' 08:50:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:50:52 INFO - Installing distribute.............................................................................................................................................................................................done. 08:50:55 INFO - Installing pip.................done. 08:50:55 INFO - Return code: 0 08:50:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:50:55 INFO - Reading from file tmpfile_stdout 08:50:55 INFO - Output received: 08:50:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:50:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:56 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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:50:56 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 08:50:56 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 08:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:56 INFO - 'HOME': '/Users/cltbld', 08:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:56 INFO - 'LOGNAME': 'cltbld', 08:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:56 INFO - 'MOZ_NO_REMOTE': '1', 08:50:56 INFO - 'NO_EM_RESTART': '1', 08:50:56 INFO - 'PAGER': '/bin/cat', 08:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:56 INFO - 'PWD': '/builds/slave/test', 08:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:56 INFO - 'SHELL': '/bin/bash', 08:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:50:56 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:56 INFO - 'USER': 'cltbld', 08:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:56 INFO - 'XPC_FLAGS': '0x0', 08:50:56 INFO - 'XPC_SERVICE_NAME': '0', 08:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:56 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 08:50:56 INFO - Cleaning up... 08:50:56 INFO - Return code: 0 08:50:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:50:56 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')]} 08:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:56 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')]} 08:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:56 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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:50:56 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 08:50:56 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 08:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:56 INFO - 'HOME': '/Users/cltbld', 08:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:56 INFO - 'LOGNAME': 'cltbld', 08:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:56 INFO - 'MOZ_NO_REMOTE': '1', 08:50:56 INFO - 'NO_EM_RESTART': '1', 08:50:56 INFO - 'PAGER': '/bin/cat', 08:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:56 INFO - 'PWD': '/builds/slave/test', 08:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:56 INFO - 'SHELL': '/bin/bash', 08:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:50:56 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:56 INFO - 'USER': 'cltbld', 08:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:56 INFO - 'XPC_FLAGS': '0x0', 08:50:56 INFO - 'XPC_SERVICE_NAME': '0', 08:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:56 INFO - Downloading/unpacking psutil>=3.1.1 08:50:56 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 08:50:56 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 08:50:56 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 08:50:56 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 08:50:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:50:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:59 INFO - Installing collected packages: psutil 08:50:59 INFO - Running setup.py install for psutil 08:50:59 INFO - building 'psutil._psutil_osx' extension 08:50:59 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 08:51:00 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 08:51:00 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 08:51:00 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 08:51:00 INFO - building 'psutil._psutil_posix' extension 08:51:00 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 08:51:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:51:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:51:00 INFO - ^ 08:51:00 INFO - 1 warning generated. 08:51:00 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 08:51:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:51:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:51:00 INFO - Successfully installed psutil 08:51:00 INFO - Cleaning up... 08:51:00 INFO - Return code: 0 08:51:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:51:00 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')]} 08:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:00 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')]} 08:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:00 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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:00 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 08:51:00 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 08:51:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:00 INFO - 'HOME': '/Users/cltbld', 08:51:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:00 INFO - 'LOGNAME': 'cltbld', 08:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:00 INFO - 'MOZ_NO_REMOTE': '1', 08:51:00 INFO - 'NO_EM_RESTART': '1', 08:51:00 INFO - 'PAGER': '/bin/cat', 08:51:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:00 INFO - 'PWD': '/builds/slave/test', 08:51:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:00 INFO - 'SHELL': '/bin/bash', 08:51:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:00 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:00 INFO - 'USER': 'cltbld', 08:51:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:00 INFO - 'XPC_FLAGS': '0x0', 08:51:00 INFO - 'XPC_SERVICE_NAME': '0', 08:51:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:51:00 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 08:51:00 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 08:51:00 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 08:51:00 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 08:51:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:51:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:51:02 INFO - Installing collected packages: mozsystemmonitor 08:51:02 INFO - Running setup.py install for mozsystemmonitor 08:51:02 INFO - Successfully installed mozsystemmonitor 08:51:02 INFO - Cleaning up... 08:51:02 INFO - Return code: 0 08:51:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:51:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:51:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:02 INFO - 'HOME': '/Users/cltbld', 08:51:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:02 INFO - 'LOGNAME': 'cltbld', 08:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:02 INFO - 'MOZ_NO_REMOTE': '1', 08:51:02 INFO - 'NO_EM_RESTART': '1', 08:51:02 INFO - 'PAGER': '/bin/cat', 08:51:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:02 INFO - 'PWD': '/builds/slave/test', 08:51:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:02 INFO - 'SHELL': '/bin/bash', 08:51:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:02 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:02 INFO - 'USER': 'cltbld', 08:51:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:02 INFO - 'XPC_FLAGS': '0x0', 08:51:02 INFO - 'XPC_SERVICE_NAME': '0', 08:51:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:02 INFO - Downloading/unpacking jsonschema==2.5.1 08:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:51:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:51:04 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 08:51:04 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 08:51:04 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 08:51:04 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 08:51:04 INFO - Downloading functools32-3.2.3-2.tar.gz 08:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:51:04 INFO - warning: no files found matching '*.txt' 08:51:04 INFO - no previously-included directories found matching 'build' 08:51:04 INFO - no previously-included directories found matching 'dist' 08:51:04 INFO - no previously-included directories found matching '.git*' 08:51:04 INFO - Installing collected packages: jsonschema, functools32 08:51:04 INFO - Running setup.py install for functools32 08:51:04 INFO - warning: no files found matching '*.txt' 08:51:04 INFO - no previously-included directories found matching 'build' 08:51:04 INFO - no previously-included directories found matching 'dist' 08:51:04 INFO - no previously-included directories found matching '.git*' 08:51:04 INFO - Successfully installed jsonschema functools32 08:51:04 INFO - Cleaning up... 08:51:04 INFO - Return code: 0 08:51:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:51:04 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')]} 08:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:04 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')]} 08:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:04 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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:04 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 08:51:04 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 08:51:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:04 INFO - 'HOME': '/Users/cltbld', 08:51:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:04 INFO - 'LOGNAME': 'cltbld', 08:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:04 INFO - 'MOZ_NO_REMOTE': '1', 08:51:04 INFO - 'NO_EM_RESTART': '1', 08:51:04 INFO - 'PAGER': '/bin/cat', 08:51:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:04 INFO - 'PWD': '/builds/slave/test', 08:51:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:04 INFO - 'SHELL': '/bin/bash', 08:51:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:04 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:04 INFO - 'USER': 'cltbld', 08:51:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:04 INFO - 'XPC_FLAGS': '0x0', 08:51:04 INFO - 'XPC_SERVICE_NAME': '0', 08:51:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:51:05 INFO - Cleaning up... 08:51:05 INFO - Return code: 0 08:51:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:51:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:05 INFO - 'HOME': '/Users/cltbld', 08:51:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:05 INFO - 'LOGNAME': 'cltbld', 08:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:05 INFO - 'MOZ_NO_REMOTE': '1', 08:51:05 INFO - 'NO_EM_RESTART': '1', 08:51:05 INFO - 'PAGER': '/bin/cat', 08:51:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:05 INFO - 'PWD': '/builds/slave/test', 08:51:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:05 INFO - 'SHELL': '/bin/bash', 08:51:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:05 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:05 INFO - 'USER': 'cltbld', 08:51:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:05 INFO - 'XPC_FLAGS': '0x0', 08:51:05 INFO - 'XPC_SERVICE_NAME': '0', 08:51:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:05 INFO - Downloading/unpacking blobuploader==1.2.4 08:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:06 INFO - Downloading blobuploader-1.2.4.tar.gz 08:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:51:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:51:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:51:07 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 08:51:07 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 08:51:07 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 08:51:07 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 08:51:07 INFO - Downloading docopt-0.6.1.tar.gz 08:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:51:07 INFO - Installing collected packages: blobuploader, requests, docopt 08:51:07 INFO - Running setup.py install for blobuploader 08:51:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:51:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:51:07 INFO - Running setup.py install for requests 08:51:08 INFO - Running setup.py install for docopt 08:51:08 INFO - Successfully installed blobuploader requests docopt 08:51:08 INFO - Cleaning up... 08:51:08 INFO - Return code: 0 08:51:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:08 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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:08 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 08:51:08 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 08:51:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:08 INFO - 'HOME': '/Users/cltbld', 08:51:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:08 INFO - 'LOGNAME': 'cltbld', 08:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:08 INFO - 'MOZ_NO_REMOTE': '1', 08:51:08 INFO - 'NO_EM_RESTART': '1', 08:51:08 INFO - 'PAGER': '/bin/cat', 08:51:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:08 INFO - 'PWD': '/builds/slave/test', 08:51:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:08 INFO - 'SHELL': '/bin/bash', 08:51:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:08 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:08 INFO - 'USER': 'cltbld', 08:51:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:08 INFO - 'XPC_FLAGS': '0x0', 08:51:08 INFO - 'XPC_SERVICE_NAME': '0', 08:51:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:08 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-6cGttX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:08 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-TJyHbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-68fPZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-ntMXUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-ffnNl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-65eFaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-c5Ctgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-jQ5jQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-WPIgY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-9jWRtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-uDIy8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:09 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-hT30KP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:10 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-Hi9SGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:10 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-MSMbTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:10 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-nga7lT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:10 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-YPJ4Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:10 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-OMceQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:51:10 INFO - Running setup.py install for manifestparser 08:51:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:51:10 INFO - Running setup.py install for mozcrash 08:51:10 INFO - Running setup.py install for mozdebug 08:51:10 INFO - Running setup.py install for mozdevice 08:51:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:51:11 INFO - Running setup.py install for mozfile 08:51:11 INFO - Running setup.py install for mozhttpd 08:51:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:51:11 INFO - Running setup.py install for mozinfo 08:51:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:51:11 INFO - Running setup.py install for mozInstall 08:51:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:51:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:51:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:51:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:51:11 INFO - Running setup.py install for mozleak 08:51:11 INFO - Running setup.py install for mozlog 08:51:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:51:12 INFO - Running setup.py install for moznetwork 08:51:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:51:12 INFO - Running setup.py install for mozprocess 08:51:12 INFO - Running setup.py install for mozprofile 08:51:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:51:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:51:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:51:12 INFO - Running setup.py install for mozrunner 08:51:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:51:12 INFO - Running setup.py install for mozscreenshot 08:51:12 INFO - Running setup.py install for moztest 08:51:12 INFO - Running setup.py install for mozversion 08:51:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:51:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:51:13 INFO - Cleaning up... 08:51:13 INFO - Return code: 0 08:51:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:13 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')]} 08:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:13 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')]} 08:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:13 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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:13 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 08:51:13 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 08:51:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:13 INFO - 'HOME': '/Users/cltbld', 08:51:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:13 INFO - 'LOGNAME': 'cltbld', 08:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:13 INFO - 'MOZ_NO_REMOTE': '1', 08:51:13 INFO - 'NO_EM_RESTART': '1', 08:51:13 INFO - 'PAGER': '/bin/cat', 08:51:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:13 INFO - 'PWD': '/builds/slave/test', 08:51:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:13 INFO - 'SHELL': '/bin/bash', 08:51:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:13 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:13 INFO - 'USER': 'cltbld', 08:51:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:13 INFO - 'XPC_FLAGS': '0x0', 08:51:13 INFO - 'XPC_SERVICE_NAME': '0', 08:51:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:13 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-WcUZth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:13 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)) 08:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:13 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-Nq777I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:13 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)) 08:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:13 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-8NBmUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:13 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)) 08:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:13 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-w5ekHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:13 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-1t49ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:13 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)) 08:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:13 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-rNvNcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:13 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)) 08:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:13 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-3c91ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-UK6KaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-yl67XQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-Pjra7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-LuTmF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-sBsMTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-vWPSBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-PAEfYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-_HkBtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:14 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)) 08:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:14 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-Bnfy1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:15 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)) 08:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:15 INFO - Running setup.py (path:/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/pip-pXAZuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:15 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)) 08:51:15 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)) 08:51:15 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)) 08:51:15 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)) 08:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:51:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:51:15 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 08:51:15 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 08:51:15 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 08:51:15 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 08:51:16 INFO - Downloading blessings-1.6.tar.gz 08:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:51:16 INFO - Installing collected packages: blessings 08:51:16 INFO - Running setup.py install for blessings 08:51:16 INFO - Successfully installed blessings 08:51:16 INFO - Cleaning up... 08:51:16 INFO - Return code: 0 08:51:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:17 INFO - Reading from file tmpfile_stdout 08:51:17 INFO - Current package versions: 08:51:17 INFO - blessings == 1.6 08:51:17 INFO - blobuploader == 1.2.4 08:51:17 INFO - docopt == 0.6.1 08:51:17 INFO - functools32 == 3.2.3-2 08:51:17 INFO - jsonschema == 2.5.1 08:51:17 INFO - manifestparser == 1.1 08:51:17 INFO - mozInstall == 1.12 08:51:17 INFO - mozcrash == 1.0 08:51:17 INFO - mozdebug == 0.1 08:51:17 INFO - mozdevice == 0.50 08:51:17 INFO - mozfile == 1.2 08:51:17 INFO - mozhttpd == 0.7 08:51:17 INFO - mozinfo == 0.9 08:51:17 INFO - mozleak == 0.1 08:51:17 INFO - mozlog == 3.4 08:51:17 INFO - moznetwork == 0.27 08:51:17 INFO - mozprocess == 0.25 08:51:17 INFO - mozprofile == 0.28 08:51:17 INFO - mozrunner == 6.13 08:51:17 INFO - mozscreenshot == 0.1 08:51:17 INFO - mozsystemmonitor == 0.3 08:51:17 INFO - moztest == 0.8 08:51:17 INFO - mozversion == 1.4 08:51:17 INFO - psutil == 3.1.1 08:51:17 INFO - requests == 1.2.3 08:51:17 INFO - wsgiref == 0.1.2 08:51:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:17 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 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:17 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 08:51:17 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 08:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:17 INFO - 'HOME': '/Users/cltbld', 08:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:17 INFO - 'LOGNAME': 'cltbld', 08:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:17 INFO - 'MOZ_NO_REMOTE': '1', 08:51:17 INFO - 'NO_EM_RESTART': '1', 08:51:17 INFO - 'PAGER': '/bin/cat', 08:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:17 INFO - 'PWD': '/builds/slave/test', 08:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:17 INFO - 'SHELL': '/bin/bash', 08:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:17 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:17 INFO - 'USER': 'cltbld', 08:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:17 INFO - 'XPC_FLAGS': '0x0', 08:51:17 INFO - 'XPC_SERVICE_NAME': '0', 08:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:17 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)) 08:51:17 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)) 08:51:17 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)) 08:51:17 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)) 08:51:17 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)) 08:51:17 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)) 08:51:17 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)) 08:51:17 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)) 08:51:17 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)) 08:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:51:17 INFO - Cleaning up... 08:51:17 INFO - Return code: 0 08:51:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaa0ca6900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, '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.xpeDpOny2o/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.Yknp0kXTqO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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 08:51:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:17 INFO - 'HOME': '/Users/cltbld', 08:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:17 INFO - 'LOGNAME': 'cltbld', 08:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:17 INFO - 'MOZ_NO_REMOTE': '1', 08:51:17 INFO - 'NO_EM_RESTART': '1', 08:51:17 INFO - 'PAGER': '/bin/cat', 08:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:17 INFO - 'PWD': '/builds/slave/test', 08:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:17 INFO - 'SHELL': '/bin/bash', 08:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:17 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:17 INFO - 'USER': 'cltbld', 08:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:17 INFO - 'XPC_FLAGS': '0x0', 08:51:17 INFO - 'XPC_SERVICE_NAME': '0', 08:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:51:17 INFO - Cleaning up... 08:51:17 INFO - Return code: 0 08:51:17 INFO - Running post-action listener: _resource_record_post_action 08:51:17 INFO - Running post-action listener: _start_resource_monitoring 08:51:17 INFO - Starting resource monitoring. 08:51:17 INFO - [mozharness: 2017-05-23 15:51:17.719140Z] Finished create-virtualenv step (success) 08:51:17 INFO - [mozharness: 2017-05-23 15:51:17.720135Z] Running install step. 08:51:17 INFO - Running pre-action listener: _resource_record_pre_action 08:51:17 INFO - Running main action method: install 08:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:17 INFO - Reading from file tmpfile_stdout 08:51:17 INFO - Detecting whether we're running mozinstall >=1.0... 08:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:51:18 INFO - Reading from file tmpfile_stdout 08:51:18 INFO - Output received: 08:51:18 INFO - Usage: mozinstall [options] installer 08:51:18 INFO - Options: 08:51:18 INFO - -h, --help show this help message and exit 08:51:18 INFO - -d DEST, --destination=DEST 08:51:18 INFO - Directory to install application into. [default: 08:51:18 INFO - "/builds/slave/test"] 08:51:18 INFO - --app=APP Application being installed. [default: firefox] 08:51:18 INFO - mkdir: /builds/slave/test/build/application 08:51:18 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'] 08:51:18 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 08:51:39 INFO - Reading from file tmpfile_stdout 08:51:39 INFO - Output received: 08:51:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:51:39 INFO - Running post-action listener: _resource_record_post_action 08:51:39 INFO - [mozharness: 2017-05-23 15:51:39.551685Z] Finished install step (success) 08:51:39 INFO - [mozharness: 2017-05-23 15:51:39.551842Z] Running run-tests step. 08:51:39 INFO - Running pre-action listener: _resource_record_pre_action 08:51:39 INFO - Running main action method: run_tests 08:51:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:39 INFO - 2017-05-23 08:51:39.566 screenresolution[1628:7398] starting screenresolution argv=screenresolution get 08:51:39 INFO - 2017-05-23 08:51:39.569 screenresolution[1628:7398] Display 0: 1600x1200x32@60 08:51:39 INFO - 2017-05-23 08:51:39.574 screenresolution[1629:7400] starting screenresolution argv=screenresolution list 08:51:39 INFO - Available Modes on Display 0 08:51:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:39 INFO - Intel Iris: 08:51:39 INFO - Chipset Model: Intel Iris 08:51:39 INFO - Type: GPU 08:51:39 INFO - Bus: Built-In 08:51:39 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:39 INFO - Vendor: Intel (0x8086) 08:51:39 INFO - Device ID: 0x0a2e 08:51:39 INFO - Revision ID: 0x0009 08:51:39 INFO - Displays: 08:51:39 INFO - Display: 08:51:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:39 INFO - Display Serial Number: 1600x1200 60 08:51:39 INFO - Main Display: Yes 08:51:39 INFO - Mirror: Off 08:51:39 INFO - Online: Yes 08:51:39 INFO - Rotation: Supported 08:51:39 INFO - Return code: 0 08:51:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:51:39 INFO - Python 2.7.3 08:51:39 INFO - Return code: 0 08:51:39 INFO - grabbing minidump binary from tooltool 08:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:39 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 0x10e3b5d30>, '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 0x10e369db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3aabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:51:39 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 08:51:39 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 08:51:39 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 08:51:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:51:39 INFO - Return code: 0 08:51:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:51:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:51:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:51:39 INFO - ENV: RUST_BACKTRACE is now 1 08:51:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:51:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:51:39 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/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 08:51:39 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/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0055 --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 08:51:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpeDpOny2o/Render', 08:51:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:39 INFO - 'HOME': '/Users/cltbld', 08:51:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:39 INFO - 'LOGNAME': 'cltbld', 08:51:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:39 INFO - 'MOZ_NO_REMOTE': '1', 08:51:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:39 INFO - 'NO_EM_RESTART': '1', 08:51:39 INFO - 'PAGER': '/bin/cat', 08:51:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:39 INFO - 'PWD': '/builds/slave/test', 08:51:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:39 INFO - 'RUST_BACKTRACE': '1', 08:51:39 INFO - 'SHELL': '/bin/bash', 08:51:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yknp0kXTqO/Listeners', 08:51:39 INFO - 'TMPDIR': '/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/', 08:51:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:39 INFO - 'USER': 'cltbld', 08:51:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:39 INFO - 'XPC_FLAGS': '0x0', 08:51:39 INFO - 'XPC_SERVICE_NAME': '0', 08:51:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:39 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/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 08:51:40 INFO - mozversion application_buildid: 20170523073546 08:51:40 INFO - mozversion application_changeset: 3a82a745123f1f0f36937a45b877724023d112cd 08:51:40 INFO - mozversion application_display_name: Nightly 08:51:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:51:40 INFO - mozversion application_name: Firefox 08:51:40 INFO - mozversion application_remotingname: firefox 08:51:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:51:40 INFO - mozversion application_vendor: Mozilla 08:51:40 INFO - mozversion application_version: 55.0a1 08:51:40 INFO - mozversion platform_buildid: 20170523073546 08:51:40 INFO - mozversion platform_changeset: 3a82a745123f1f0f36937a45b877724023d112cd 08:51:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:51:40 INFO - mozversion platform_version: 55.0a1 08:51:40 INFO - using testdate: 1495554700 08:51:40 INFO - actual date: 1495554700 08:51:40 INFO - starting webserver on 'localhost:49219' 08:51:40 INFO - SUITE-START | Running 2 tests 08:51:40 INFO - TEST-START | dromaeo_css 08:51:40 INFO - operating with platform_type : mac_ 08:51:40 INFO - Initialising browser for dromaeo_css test... 08:51:40 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/tmpOwcO0n/profile http://localhost:49219/getInfo.html) 08:51:40 INFO - PID 1639 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 08:51:42 INFO - PID 1639 | 2017-05-23 08:51:42.901 plugin-container[1644:7625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e4f, name = 'com.apple.tsm.portname' 08:51:42 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:42 INFO - PID 1639 | 2017-05-23 08:51:42.906 plugin-container[1644:7625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b27, name = 'com.apple.CFPasteboardClient' 08:51:42 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:42 INFO - PID 1639 | 2017-05-23 08:51:42.906 plugin-container[1644:7625] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:51:43 INFO - PID 1639 | __metrics Screen width/height:1600/1200 08:51:43 INFO - PID 1639 | colorDepth:24 08:51:43 INFO - PID 1639 | Browser inner width/height: 1024/691 08:51:43 INFO - PID 1639 | __metrics 08:51:43 INFO - PID 1639 | 2017-05-23 08:51:43.454 plugin-container[1645:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 08:51:43 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:43 INFO - PID 1639 | 2017-05-23 08:51:43.454 plugin-container[1645:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 08:51:43 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:43 INFO - PID 1639 | 2017-05-23 08:51:43.454 plugin-container[1645:7688] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:51:44 INFO - TEST-INFO | 1639: exit 0 08:51:44 INFO - Browser initialized. 08:51:44 INFO - Running cycle 1/1 for dromaeo_css test... 08:51:44 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/tmpOwcO0n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:46 INFO - PID 1646 | 2017-05-23 08:51:46.142 plugin-container[1647:7781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 08:51:46 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:46 INFO - PID 1646 | 2017-05-23 08:51:46.143 plugin-container[1647:7781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 08:51:46 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:46 INFO - PID 1646 | 2017-05-23 08:51:46.143 plugin-container[1647:7781] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:51:46 INFO - PID 1646 | 2017-05-23 08:51:46.629 plugin-container[1648:7829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 08:51:46 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:46 INFO - PID 1646 | 2017-05-23 08:51:46.630 plugin-container[1648:7829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 08:51:46 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:51:46 INFO - PID 1646 | 2017-05-23 08:51:46.630 plugin-container[1648:7829] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:51:56 INFO - PID 1646 | RSS: Main: 239652864 08:51:56 INFO - PID 1646 | 08:54:59 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 08:55:00 INFO - PID 1646 | RSS: Main: 267165696 08:55:00 INFO - PID 1646 | 08:58:02 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 08:58:03 INFO - PID 1646 | RSS: Main: 272093184 08:58:03 INFO - PID 1646 | 09:01:04 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 09:01:05 INFO - PID 1646 | RSS: Main: 274972672 09:01:05 INFO - PID 1646 | 09:04:07 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 09:04:07 INFO - PID 1646 | RSS: Main: 273379328 09:04:07 INFO - PID 1646 | 09:07:10 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 09:07:11 INFO - PID 1646 | RSS: Main: 274444288 09:07:11 INFO - PID 1646 | 09:10:12 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 09:10:13 INFO - PID 1646 | RSS: Main: 275578880 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | __start_tp_report 09:10:13 INFO - PID 1646 | _x_x_mozilla_page_load 09:10:13 INFO - PID 1646 | _x_x_mozilla_page_load_details 09:10:13 INFO - PID 1646 | |i|pagename|runs| 09:10:13 INFO - PID 1646 | |0;dojo.html;3525.4359302511593;3568.3220188164246;3596.586392564856;3610.63893610639;3611.801350925699;5453.99101166284;5862.501687356575;6026.517878569714;6237.781677641284;6243.375662433755;6154.507639388849;6198.44214020738;6207.255129384469;6229.34591867853;6263.217097862767;5408.107662235727;5434.347878254615;5468.343798744147;5481.917771233435;5566.610337276392;5354.732263386815;5537.058700020986;5540.2243685884005;5551.278333816598;5586.944130558703;5492.066348720727;5556.555475561966;5557.6109672322955;5583.441655834425;5603.439656034405;5400.540954018913;5418.241446197536;5438.83683489496;5475.397706252309;5538.280023596947;4955.529224723646;5105.668131571473;5450.236966824647;5473.616846820724;5489.780408783644;4720.645951553613;6139.109829074804;6237.812865614039;6250;6271.623702577815;6042.667653279068;6326.398992095744;6609.603423794588;6641;6648.524678245061;6486.318936511646;6642.169728783902;6733.4276586490205;6749.392554670103;6815.182178138605;6310.684465776692;6314.747410103636;6350.587211831263;6359.20509936258;6374.235091788923;6849.315068493111;6945.131858517685;6948.305169483011;6955.6869940852785;6957.9304206958295;6343.714532846033;6431.035344698239;6452.161219041588;6576.276609572943;6595.868082638321;5835.67903765297;6692.096566963495;6702.061711360413;6743.629100399427;6750.696218670171;5934.465898069195;6392.840178995562;6639.800805975829;6640.0703901453835;6646.20245570525;4877.853664390003;5426.565874730012;6560.245571759213;6688.163979502562;6700.363465470772;5233.973830130825;5347.625666203407;5352.5450336721815;5366.731663416814;5371.838844834661;4690.788914498856;4712.740799256074;4739.028499157734;4845.854624361275;4883.731394773251;6269.96865015672;6329.430351268408;6357.904631430532;6366.140571022946;6381.8085457436355;6807.455403567703;6823.1812182538415;6824;6826.522143449911;6828.5220034597105;5480.424555421703;6278.560500764903;6316.178896743394;6361.554691171574;6382.936170638327;5045.646804723708;5937.614055086492;6031.547633927473;6062.181605483292;6067.75728970845;6909.930900690929;6922.93077069243;6972.1284839395075;6973.093497845349;6975.6163411012885;6757.729690812313;6790.626515541692;6805.217399998865;6812.386885180357;6818.420434263143;6694.531382803157;6812.4890633203695;6831;6856.828579285558;6896.310368963063;6650.700718467778;6652.800415987528;6655.500837437115;6672.499562532732;6673.733050677918;6708.8993665094085;6763.154605674291;6766.729330826909;6793.762218322335;6794.082798822095;6340.524460665561;6388.201474815648;6469.223693156851;6540.247871494648;6565.80302590923;5253.290805741329;6465.741370345134;6507.446867016167;6568.671566421755;6588.637624930675;5674.546036317002;5991.820245392645;6266.248050234001;6349.714262858275;6386.712597933011;6446.806595802133;6530.934690653033;6542.920418130955;6551.312112228338;6565.737370505126;6174.845628859314;6229.595076320024;6299.401556852092;6306.779262725782;6355.300916899045 09:10:13 INFO - PID 1646 | |1;ext.html;677.6137601567106;787.1420152034285;824.0153017144511;1207.2515040674782;1259.6724851538609;39501;40008.39987400188;40109.39562417507;40156.99999999996;40189;39992.60007399925;40207.79376618699;40278.78466684331;40399;40540.59459405405;6462.903056454157;6991.475639327058;8234.135415781346;8418.747437576883;8429.114942930995;8467.872981905277;8471;8541.487510749344;8572.399932004762;8573;8517.105703901094;8549.40154189207;8571.442856214357;8572.571371431435;8593.871091933626;7199.784006479787;8480.491170529787;8572.828543429128;8573.699920502815;8574.614142363545;8405.579721013986;8460.323174146053;8464.78838029048;8483.406161568693;8491.66033358665;36930;37144.628553714654;37186.88439346824;37225.44161837575;37267.813660931795;3623.4383670530847;3656.963430365689;3660.9749270204384;3661.9450708239397;3663.761855479412;30197.849010755024;30366.54450183249;30498.84750576255;30562.84718576393;30650.54024189639;2675.652165218529;3092.2114860710376;3093.041157241262;3119.4229067622564;3126.515390114536;171370;237369.25261494846;241699.58300416946;260371.9999999981;285996;46940.76529617398;47225.5277447228;47379.052418951425;47451.5254847454;47509;48923.755381222865;48948.26577601339;49032.52902412933;49089.77275568068;49349.0130197394;14282;14432.134071955716;14462.638434039232;14462.855371446361;14473.855261447461;11650.941745291218;11701.707457313636;11776.94111529454;11794.938455539042;11806;13380.933095334656;13437.059405841497;13551.322433878267;13567.89697596874;13720.519781807589;6104.694765261719;6130.846728831815;6162.845928851725;6169.629822210592;6170.136180934673;1023.3399457349967;1056.4612047855649;1058.756486008207;1083.1551389915878;1089.373610174153;23371.7662823373;24281.635775463383;24587.754122458904;24602.753972460403;24647.01411943502;1092.759592889563;1142.354569668135;1147.6499667601295;1176.329492189461;1179.604832381162;12255.080868934865;12533.623991280276;14178.361973711208;16771.2452939618;16839.579010524834;288899.999000091;289976;290923;291062;291173;56255.43744562584;56409.71795140998;56813.715931420906;57020.714896425256;57088.42911570914;1474.388128926505;1497;1540.306861912144;1554.5958050906845;1556.159673776148;24666.50666986686;27915.441691165655;28331.575026375034;28595.857020715597;28718.138455846358;32401.675983239867;38554.22891542209;38574.6142538571;38588.6141138585;38613.2277354457;276283;276287;276683;277416;277677;23709.762902370756;29279.560806588343;33997.980060598224;35119.29761404809;37197.07007324838;37787;46995.29507057329;48875.26687099625;48968.51031489782;49246.50753492419;29331.413371732873;30639.395651368992;31822.56798444029;32612.83693581517;33020.17449563684;32297.838510808237;32801.34397312088;32942;33008.3495825203;33113.834430827694;39798.60201398065;40032.599674002886;40089.39865902072;40129.39805902856;40386.79806600948;30419.514141767555;32107.678923211402;33150.502742459365;33157.66842331546;33221.66778332186;23999;25596;25857.078087399477;26467.205983820517;26734.732652674003 09:10:13 INFO - PID 1646 | |2;jquery.html;433.7635988386329;438.95829896159853;441.25428026634995;455.09890416974395;459.3316724166338;18614.534636634075;19600.117994690238;21979.23072692452;22092.88953555234;22118.778812211873;16663.916680416613;21669.783302166972;21743.9128043598;21914;22012.248468941383;5760.48155665991;5932.317783454893;5951.791687290945;6002.129691194774;6009.87980240395;5745.454181852718;5894.6168499047635;5952.642841429506;5971.761129554834;5981.760729570812;5881.8823623527505;5916.26046744157;5924.200232968558;5936.762529498815;5965.821025369224;5665.348484924245;5895.086261629454;5953.047512398039;5954.851128721773;5955.272970838451;5071.8985620287385;5253.711045892474;5295.205719142159;5299.231611416361;5303.469653034705;26515.469690606078;26532.46935061307;26837;26864.73135268642;27207.591886121725;2051.4050925231663;2141.9785802141937;2172.4351668566287;2173.4240426287047;2173.7608863025052;17563;17583.824161758348;17703.822961770347;18039.278428862828;18092.90953545237;2063;2140.422086036777;2159.373781603333;2166.5991791518463;2169.8155656769195;148015.2599236997;162964;165666;167105;172380.13809930996;12210.98518744415;12500.749985000431;12523;12555.74888502243;12643.43104560297;15737.370505179893;15866.52400427989;15918.363265469483;15930.761038584431;15942.521724348288;7732.4200684948855;8302.958485207657;8822.77943051416;8826.911730882737;8852.336074794417;8345.040320363114;8393.118722534045;8404.41169118168;8565.27195188416;8589.52757598338;6759.493038022168;7512.32389084985;7654.540727556365;7736.381089512825;7793.220677932161;1860.8604354673182;1927.2772710233662;2016.4070384396457;2195.9560808783735;2375.4536456615074;941.6798288581915;960.8270511307892;964.7395203295212;966.6761634852263;1011.595361855249;17554.824451755318;18905.7164142538;19943;20528.486787830425;20557.074931628114;994.1003391930315;998.5805961496044;1020.2144348851343;1032.0711359776262;1033.7674023344687;7768.378529717722;10455.372677639367;10477.37135771856;10492.895071049192;10495.317804342692;519448;520729;528289;533388;540104;122307.38846305711;123564.38217808853;125073;127238;128586.35706821406;1075.1506310015156;1160.2690305107728;1183.8875306845837;1186.4067966016992;1186.5372504723166;17207.225674844412;17741.556461088578;17805.732914006043;18015.459536213933;18202.998835064198;40273.798631006655;53567.73216133894;55719;56381.71809141092;56387;64833;70955.93566096616;75060;76526.6173669128;76795.2320476788;8433.283170930294;8504.957475212585;8552.57237138153;8657.91342086571;8880.111988801069;11893.345865977455;12051.939740301243;13548.59354219375;13758.243296618783;14035.578932632037;11846.466908989289;11997.520099196283;12116.697082573006;12136.211146275464;12176.330301833485;10027.348222365523;10956.26093476631;11009;11039.061679757311;11279.7744045117;10599.364038157735;10840.566377345134;12060;12078.577249796215;12200.633980980585;6879.380855723009;7494.700211991677;8200.958995205225;8737.301015918838;8967;11141.830107838885;11558.711032224262;11809.822852657046;11923.284602923852;11965.76068478643 09:10:13 INFO - PID 1646 | |3;mootools.html;1277.6103288497009;1467.589075058983;1468.588795137362;1470.7058588282339;1471.227605507109;11044.006039456448;11379.772404551913;11764;11878.821817672742;11885.702857428558;11818.17272790905;11844.289342639426;11993.340366279852;12021.699457513567;12132.272063676186;2055.3628375203734;2508.49830033993;2594.3514121469634;2604.0625374865035;2610.2169349195165;2512.2588836293244;2551.2473820222986;2569.254916074336;2569.409035921739;2574.871256437171;2539.7841183499427;2556.7954563634867;2587.1462417402213;2589.04205443986;2589.4303253284247;2495.238952119603;2519.5212909547126;2526.2800101970943;2548.9107881224068;2553.374423266293;2475.665785119004;2543.26245388837;2543.7710606045543;2585.6380106785064;2586.935326616831;11626.186166968315;11657.941710291394;11753.412329383496;11762.411879405996;11775.293482391084;6502.7398904043785;6675.365840245169;6712.060311556337;6728.3271672832325;6742.359475849787;3451.3097380523745;4014.658754005913;4295.441592592974;4303.634191093761;4374.431323927901;1209.1173443386376;1222.1322860768864;1240.5161986825146;1244.116677159218;1276.0748457368427;14898.627534311621;15202.239888005555;16148.677026459523;16299.755503667455;16310.266038028318;5351.5718742501285;5439.592030597721;5639.238702775155;5680.943190568124;5692.288463942007;11605.535778568932;11621.535138594361;11643;11645.767084658259;11693.766124677459;6438.45273151778;6453.032045193165;6456.128422662974;6461.127747754087;6464.127342808661;6025.517958563304;6135.263768347738;6137.754489820357;6227;6261.123442718023;3360.8655653773576;3724.1434470071495;3744.9514136041953;4380.14587155508;4412.933805992912;1061.7345663584103;1109.6615532262576;1230.680023193976;1266.6200139957975;1298.9608313349472;587.759018802289;631.9557630965788;770.5954373953607;786.1195461083623;807.6446363599997;6028.5478589106;6043;6154.9384506154365;6224.37756224374;6245.718942647592;478.6912441475229;533.9065663508948;544.300573762713;552.4337554007094;597.9760809567656;3808;4038.374052021941;4213.6207741302815;4274.102438487952;4311.353297005474;14302.284885755671;14819.962602617714;14835.703285934436;14842.851571484363;14893.478728244676;13014.804777928339;14298.070625409315;14612.196329201995;14748.41006359755;14754.114753114633;8403.663853445916;8449.53527555978;8490.660373584988;8499.277561407349;8508.787280317918;5950.988331983486;5977.491913187428;6202.472789812736;6816.386525212754;6900.827479313058;10527.263091583516;12018.819717704417;12040.518379265082;12205.450754716237;12231.633051008484;12463.314517701614;12555.87444125547;13016.349182541024;13142.80285795733;13212.801807972695;7874.606269686378;8052.074011488754;8073.596320184085;8098.878516822135;8108;9143.908560914306;9200.631974721204;9281.118293762082;9285.442873427615;9294.349395542247;8638.093000234885;8922.776930576787;8942.329325300498;8946.971098323776;8986.19124278818;8634.266087382643;8685.261752750834;8756;8773.42972706772;8774.780630484289;7775.183605721318;7897.644605992629;7993.840123197388;8002;8029.759107226792;4989.35138432016;5085.211792172219;5925.4370834770625;6019.458248757458;6065.545084118797;6006.579539432198;6085.543584231112;6178.351273116258;6206.968965155326;6323.019931910561 09:10:13 INFO - PID 1646 | |4;prototype.html;397.89455794214535;401.4379868184542;402.7804846358734;404.6034885811906;412.6018392251475;6154.69226538673;6294.685265736712;6294.716737746801;6347.777827776029;6362.236531616201;6013.466566025668;6016.699165041752;6123.571350005502;6177.135201071842;6207.813765587029;3869.6323849234277;4713.670043096985;4721.907150816221;4727.290906364038;4732.171869922767;4579.450465944092;4650.233111298789;4724.078804633098;4746.335513028179;4754.263089221176;3760.0975765816142;3971.463852379935;4663.487016428207;4676.690526652543;4699.6710230283725;3787.5644300905337;4683.742394168319;4687.671862969593;4691.812327506896;4693.389859318267;4132.896677583084;4140.875773726793;4141.7929103545;4146.854860079913;4173.08192197716;5922.230110085661;6041.167066346905;6074;6095.299040610343;6100.664463454508;4538.977305113453;5251.973740131313;5578.246936663539;5589.161625756128;5603.495685388335;6447.516436267252;6601.471882249409;6639.900401493981;6666.400063984657;6670.7331706731675;4702.764861756933;4721.362616046824;4725.456572494173;4736.484325015997;4758.119747846625;6139.693015349215;6199.535034872358;6634.203895532567;6971.233164351968;7056.611886346249;3705.6294370562728;3729.7202709796875;4404.568515232499;4428.623566996841;4432.113577284556;3447.827608619559;4010.9972506873282;4158.897892058546;4514.503404625489;4515.81936722534;4939.753012349368;5339.410385962805;5382.91925621116;5390.137577987503;5413.350397952271;5163.690178589297;6497.772577959749;6683.899741503881;6709.261981182066;6718.798436046926;1434.619825746178;1451.9618472792004;1487.4347747855961;1528.7630417285338;1537.1545649892514;869.9826003480022;992.8858181309098;998.1715176403568;999.9051039112161;1016.5628779624684;5341.919871201935;6297.6155387144245;6322.272938612026;6350.968245158837;6378.77674281398;5467.863303417446;6350.237971443364;6887.5178737488895;6893.827654308682;6902.413294869892;5503.3395992480355;5759.005317235014;6226.439620434183;6310.7475700971445;6315.873682526416;3125;3403.914902127417;3605.5673319201865;3606.6213047630145;3607.7113830893463;6017.916774980546;6115.663638499836;6251.218597675291;6552.344765523505;7079.610621415872;5934.821955341346;6163.660998645118;6493.837654058591;6749.966250168718;7062.293770623;6480.546361754727;6843.657817109125;6948.826279343057;7140.285971402922;7147.606881621561;5764.654120752768;6965.7213711453005;6983.895241571279;7012.158540975116;7104.538205016657;5741.311042674905;6398.90401643985;6504.959206526933;6541.378569035934;6977.895331569929;4930.101910764453;6380.680965951591;7004.859902802017;7104.786856394317;7167.462440316893;3922.9019274517223;4460.8661740147845;4474.776261186993;4477.1493416252115;4490.820367185407;6138.877222455437;6826.351496607815;6892.140401375246;7015.298470152944;7054.294570542905;6318.494520438446;6336.271328797247;6698.588508137164;6742.5280230383405;6773.525853190229;6138.754449821957;6436.67816609177;6677.465802735866;6721.361470660279;6749.966250168718;6020.939790602038;6274.960637792645;6550.148480697479;6607.80176594703;6625.933740662532;6001.769637224445;6201.007838745779;6333.619982801047;6744.393004629411;6782.593044417351;5370.597205209547;6181.9690901547;6429.97120460724;6519.837004074746;6540.313267106885 09:10:13 INFO - PID 1646 | |5;yui.html;769.8806684963829;812.6261919517021;833.4041160570191;839.6893149534669;865.6926790989198;16099.275532601032;16640.66718665626;16672.583185420357;18457.723134153;19164.71252931207;17621.55946101348;18012.63974720508;18065.187066582002;18463.44609661709;18784.62430751384;1007.4056306778999;1021.2953062386929;1025.8820235672881;1029.85067165261;1031.6028329093315;1005.4570531912794;1010.6412223660604;1013.3717095400825;1019.0115587879744;1019.108280254777;1052.8420736889452;1059.3379138038727;1069.1393428290223;1076.359566058194;1081.5187241677456;913.7989642278689;1062.7077553672725;1074.8226542620457;1077.8922107789238;1079.557381473596;770.8650986077414;780.3133242746347;780.8516381887423;795.3637090327715;798.0383637716537;16903.98576085439;17236.310547578083;17470.301187952467;17492.988306344807;17519.73720394195;16828.990260584404;17005.23476443563;17044.31822727102;17046.14769261532;17097;13801.51694690691;14119.082259653193;14424.855751442561;14570;14582.125072495684;3549.8225088745717;3598.226381328001;3623.058004918714;3628;3640.308341415123;98690;111104;111439;112073;113096.4345178277;13472.932635336761;13631.86368136306;13744.587662370144;13752.106113102616;13789.724205516035;24396.634050489258;25294;25540;25869.74130258711;25912.611310830354;7840.176781437867;7873.763787086396;7922.801929951797;7969.561674107979;7999.720009799629;3827.3110840048503;3894.844206231776;3895.0651843557343;3907.78507182102;3908.3160446921675;4306.397104405386;4323.978380108079;4373.912521749611;4420.84527041552;4440.311751678495;744.8361360500681;831.4595512916652;857.2028013946964;861;868.0321441592705;654.6105067484839;667.3460009190923;676.2155899156954;683.774354463026;686.7459040155175;10847.457627118612;11233.32600044;11917.523299068114;11990.640280791591;12076.758464830655;640.5228105061666;641.3394203969918;650.0866282872572;657.5528640524394;658.785894584262;4791.736454494967;5334;5431.21247419122;5568.638038527563;5596.188552659923;78507.64477065697;96769.5161524202;102437;105941.9405805932;106698.46650766696;79909.20090799134;80111.59944200359;80668.19331806607;80861;98278;71913.64043179751;72499.91250131252;73343.63328183324;73526.63236683782;74705.25294747092;9416;12348.753024939631;12584.622461326175;12820.551280705131;12873.871261287642;36775.816120920295;37290.067748305424;37514.06214844651;38076.66731664489;38678.033049173995;47313;47881;47976.56070317896;48165.555033349054;49036.75481622569;15978.40215978393;16059.919700401424;16401.753973690167;16534.83465165333;16535.751963720795;14048;17588.73616895773;18293.634127317644;18313.633727325647;18593.535161620526;16401.917990409973;16412.343506259615;16562.917185413997;16699.749503757695;16875.662486749952;16207.432739854048;16278.430254941493;16572.50282491527;16729.49811505657;16868.987860728397;15629.140397277804;16272.023678579322;16323.59191020234;16341.10123943147;16540.66918661596;9756.51217439111;11724.296542207494;11857.110716696456;11962.282263064244;12373.505059797868;12397.070219733736;12536.623901282976;13464.990125740413;13548.52580159729;13816.792748108586 09:10:13 INFO - PID 1646 | __end_tp_report 09:10:13 INFO - PID 1646 | __start_cc_report 09:10:13 INFO - PID 1646 | _x_x_mozilla_cycle_collect,352 09:10:13 INFO - PID 1646 | __end_cc_report 09:10:13 INFO - PID 1646 | __startTimestamp1495555813226__endTimestamp 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | ------- Summary: start ------- 09:10:13 INFO - PID 1646 | Number of tests: 6 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6116.45 Median:6343.71 stddev:741.54 (11.7%) stddev-sans-first:715.41 09:10:13 INFO - PID 1646 | Values: 3525.4 3568.3 3596.6 3610.6 3611.8 5454.0 5862.5 6026.5 6237.8 6243.4 6154.5 6198.4 6207.3 6229.3 6263.2 5408.1 5434.3 5468.3 5481.9 5566.6 5354.7 5537.1 5540.2 5551.3 5586.9 5492.1 5556.6 5557.6 5583.4 5603.4 5400.5 5418.2 5438.8 5475.4 5538.3 4955.5 5105.7 5450.2 5473.6 5489.8 4720.6 6139.1 6237.8 6250.0 6271.6 6042.7 6326.4 6609.6 6641.0 6648.5 6486.3 6642.2 6733.4 6749.4 6815.2 6310.7 6314.7 6350.6 6359.2 6374.2 6849.3 6945.1 6948.3 6955.7 6957.9 6343.7 6431.0 6452.2 6576.3 6595.9 5835.7 6692.1 6702.1 6743.6 6750.7 5934.5 6392.8 6639.8 6640.1 6646.2 4877.9 5426.6 6560.2 6688.2 6700.4 5234.0 5347.6 5352.5 5366.7 5371.8 4690.8 4712.7 4739.0 4845.9 4883.7 6270.0 6329.4 6357.9 6366.1 6381.8 6807.5 6823.2 6824.0 6826.5 6828.5 5480.4 6278.6 6316.2 6361.6 6382.9 5045.6 5937.6 6031.5 6062.2 6067.8 6909.9 6922.9 6972.1 6973.1 6975.6 6757.7 6790.6 6805.2 6812.4 6818.4 6694.5 6812.5 6831.0 6856.8 6896.3 6650.7 6652.8 6655.5 6672.5 6673.7 6708.9 6763.2 6766.7 6793.8 6794.1 6340.5 6388.2 6469.2 6540.2 6565.8 5253.3 6465.7 6507.4 6568.7 6588.6 5674.5 5991.8 6266.2 6349.7 6386.7 6446.8 6530.9 6542.9 6551.3 6565.7 6174.8 6229.6 6299.4 6306.8 6355.3 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:43081.65 Median:33067.00 stddev:70676.68 (213.7%) stddev-sans-first:70803.23 09:10:13 INFO - PID 1646 | Values: 677.6 787.1 824.0 1207.3 1259.7 39501.0 40008.4 40109.4 40157.0 40189.0 39992.6 40207.8 40278.8 40399.0 40540.6 6462.9 6991.5 8234.1 8418.7 8429.1 8467.9 8471.0 8541.5 8572.4 8573.0 8517.1 8549.4 8571.4 8572.6 8593.9 7199.8 8480.5 8572.8 8573.7 8574.6 8405.6 8460.3 8464.8 8483.4 8491.7 36930.0 37144.6 37186.9 37225.4 37267.8 3623.4 3657.0 3661.0 3661.9 3663.8 30197.8 30366.5 30498.8 30562.8 30650.5 2675.7 3092.2 3093.0 3119.4 3126.5 171370.0 237369.3 241699.6 260372.0 285996.0 46940.8 47225.5 47379.1 47451.5 47509.0 48923.8 48948.3 49032.5 49089.8 49349.0 14282.0 14432.1 14462.6 14462.9 14473.9 11650.9 11701.7 11776.9 11794.9 11806.0 13380.9 13437.1 13551.3 13567.9 13720.5 6104.7 6130.8 6162.8 6169.6 6170.1 1023.3 1056.5 1058.8 1083.2 1089.4 23371.8 24281.6 24587.8 24602.8 24647.0 1092.8 1142.4 1147.6 1176.3 1179.6 12255.1 12533.6 14178.4 16771.2 16839.6 288900.0 289976.0 290923.0 291062.0 291173.0 56255.4 56409.7 56813.7 57020.7 57088.4 1474.4 1497.0 1540.3 1554.6 1556.2 24666.5 27915.4 28331.6 28595.9 28718.1 32401.7 38554.2 38574.6 38588.6 38613.2 276283.0 276287.0 276683.0 277416.0 277677.0 23709.8 29279.6 33998.0 35119.3 37197.1 37787.0 46995.3 48875.3 48968.5 49246.5 29331.4 30639.4 31822.6 32612.8 33020.2 32297.8 32801.3 32942.0 33008.3 33113.8 39798.6 40032.6 40089.4 40129.4 40386.8 30419.5 32107.7 33150.5 33157.7 33221.7 23999.0 25596.0 25857.1 26467.2 26734.7 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:34536.37 Median:21828.96 stddev:90190.55 (413.2%) stddev-sans-first:90407.21 09:10:13 INFO - PID 1646 | Values: 433.8 439.0 441.3 455.1 459.3 18614.5 19600.1 21979.2 22092.9 22118.8 16663.9 21669.8 21743.9 21914.0 22012.2 5760.5 5932.3 5951.8 6002.1 6009.9 5745.5 5894.6 5952.6 5971.8 5981.8 5881.9 5916.3 5924.2 5936.8 5965.8 5665.3 5895.1 5953.0 5954.9 5955.3 5071.9 5253.7 5295.2 5299.2 5303.5 26515.5 26532.5 26837.0 26864.7 27207.6 2051.4 2142.0 2172.4 2173.4 2173.8 17563.0 17583.8 17703.8 18039.3 18092.9 2063.0 2140.4 2159.4 2166.6 2169.8 148015.3 162964.0 165666.0 167105.0 172380.1 12211.0 12500.7 12523.0 12555.7 12643.4 15737.4 15866.5 15918.4 15930.8 15942.5 7732.4 8303.0 8822.8 8826.9 8852.3 8345.0 8393.1 8404.4 8565.3 8589.5 6759.5 7512.3 7654.5 7736.4 7793.2 1860.9 1927.3 2016.4 2196.0 2375.5 941.7 960.8 964.7 966.7 1011.6 17554.8 18905.7 19943.0 20528.5 20557.1 994.1 998.6 1020.2 1032.1 1033.8 7768.4 10455.4 10477.4 10492.9 10495.3 519448.0 520729.0 528289.0 533388.0 540104.0 122307.4 123564.4 125073.0 127238.0 128586.4 1075.2 1160.3 1183.9 1186.4 1186.5 17207.2 17741.6 17805.7 18015.5 18203.0 40273.8 53567.7 55719.0 56381.7 56387.0 64833.0 70955.9 75060.0 76526.6 76795.2 8433.3 8505.0 8552.6 8657.9 8880.1 11893.3 12051.9 13548.6 13758.2 14035.6 11846.5 11997.5 12116.7 12136.2 12176.3 10027.3 10956.3 11009.0 11039.1 11279.8 10599.4 10840.6 12060.0 12078.6 12200.6 6879.4 7494.7 8201.0 8737.3 8967.0 11141.8 11558.7 11809.8 11923.3 11965.8 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:6854.59 Median:4026.52 stddev:4313.83 (107.1%) stddev-sans-first:4305.57 09:10:13 INFO - PID 1646 | Values: 1277.6 1467.6 1468.6 1470.7 1471.2 11044.0 11379.8 11764.0 11878.8 11885.7 11818.2 11844.3 11993.3 12021.7 12132.3 2055.4 2508.5 2594.4 2604.1 2610.2 2512.3 2551.2 2569.3 2569.4 2574.9 2539.8 2556.8 2587.1 2589.0 2589.4 2495.2 2519.5 2526.3 2548.9 2553.4 2475.7 2543.3 2543.8 2585.6 2586.9 11626.2 11657.9 11753.4 11762.4 11775.3 6502.7 6675.4 6712.1 6728.3 6742.4 3451.3 4014.7 4295.4 4303.6 4374.4 1209.1 1222.1 1240.5 1244.1 1276.1 14898.6 15202.2 16148.7 16299.8 16310.3 5351.6 5439.6 5639.2 5680.9 5692.3 11605.5 11621.5 11643.0 11645.8 11693.8 6438.5 6453.0 6456.1 6461.1 6464.1 6025.5 6135.3 6137.8 6227.0 6261.1 3360.9 3724.1 3745.0 4380.1 4412.9 1061.7 1109.7 1230.7 1266.6 1299.0 587.8 632.0 770.6 786.1 807.6 6028.5 6043.0 6154.9 6224.4 6245.7 478.7 533.9 544.3 552.4 598.0 3808.0 4038.4 4213.6 4274.1 4311.4 14302.3 14820.0 14835.7 14842.9 14893.5 13014.8 14298.1 14612.2 14748.4 14754.1 8403.7 8449.5 8490.7 8499.3 8508.8 5951.0 5977.5 6202.5 6816.4 6900.8 10527.3 12018.8 12040.5 12205.5 12231.6 12463.3 12555.9 13016.3 13142.8 13212.8 7874.6 8052.1 8073.6 8098.9 8108.0 9143.9 9200.6 9281.1 9285.4 9294.3 8638.1 8922.8 8942.3 8947.0 8986.2 8634.3 8685.3 8756.0 8773.4 8774.8 7775.2 7897.6 7993.8 8002.0 8029.8 4989.4 5085.2 5925.4 6019.5 6065.5 6006.6 6085.5 6178.4 6207.0 6323.0 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5324.50 Median:6108.16 stddev:1675.72 (27.4%) stddev-sans-first:1639.11 09:10:13 INFO - PID 1646 | Values: 397.9 401.4 402.8 404.6 412.6 6154.7 6294.7 6294.7 6347.8 6362.2 6013.5 6016.7 6123.6 6177.1 6207.8 3869.6 4713.7 4721.9 4727.3 4732.2 4579.5 4650.2 4724.1 4746.3 4754.3 3760.1 3971.5 4663.5 4676.7 4699.7 3787.6 4683.7 4687.7 4691.8 4693.4 4132.9 4140.9 4141.8 4146.9 4173.1 5922.2 6041.2 6074.0 6095.3 6100.7 4539.0 5252.0 5578.2 5589.2 5603.5 6447.5 6601.5 6639.9 6666.4 6670.7 4702.8 4721.4 4725.5 4736.5 4758.1 6139.7 6199.5 6634.2 6971.2 7056.6 3705.6 3729.7 4404.6 4428.6 4432.1 3447.8 4011.0 4158.9 4514.5 4515.8 4939.8 5339.4 5382.9 5390.1 5413.4 5163.7 6497.8 6683.9 6709.3 6718.8 1434.6 1452.0 1487.4 1528.8 1537.2 870.0 992.9 998.2 999.9 1016.6 5341.9 6297.6 6322.3 6351.0 6378.8 5467.9 6350.2 6887.5 6893.8 6902.4 5503.3 5759.0 6226.4 6310.7 6315.9 3125.0 3403.9 3605.6 3606.6 3607.7 6017.9 6115.7 6251.2 6552.3 7079.6 5934.8 6163.7 6493.8 6750.0 7062.3 6480.5 6843.7 6948.8 7140.3 7147.6 5764.7 6965.7 6983.9 7012.2 7104.5 5741.3 6398.9 6505.0 6541.4 6977.9 4930.1 6380.7 7004.9 7104.8 7167.5 3922.9 4460.9 4474.8 4477.1 4490.8 6138.9 6826.4 6892.1 7015.3 7054.3 6318.5 6336.3 6698.6 6742.5 6773.5 6138.8 6436.7 6677.5 6721.4 6750.0 6020.9 6275.0 6550.1 6607.8 6625.9 6001.8 6201.0 6333.6 6744.4 6782.6 5370.6 6182.0 6430.0 6519.8 6540.3 09:10:13 INFO - PID 1646 | 09:10:13 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:20498.03 Median:18038.91 stddev:27533.56 (152.6%) stddev-sans-first:27570.95 09:10:13 INFO - PID 1646 | Values: 769.9 812.6 833.4 839.7 865.7 16099.3 16640.7 16672.6 18457.7 19164.7 17621.6 18012.6 18065.2 18463.4 18784.6 1007.4 1021.3 1025.9 1029.9 1031.6 1005.5 1010.6 1013.4 1019.0 1019.1 1052.8 1059.3 1069.1 1076.4 1081.5 913.8 1062.7 1074.8 1077.9 1079.6 770.9 780.3 780.9 795.4 798.0 16904.0 17236.3 17470.3 17493.0 17519.7 16829.0 17005.2 17044.3 17046.1 17097.0 13801.5 14119.1 14424.9 14570.0 14582.1 3549.8 3598.2 3623.1 3628.0 3640.3 98690.0 111104.0 111439.0 112073.0 113096.4 13472.9 13631.9 13744.6 13752.1 13789.7 24396.6 25294.0 25540.0 25869.7 25912.6 7840.2 7873.8 7922.8 7969.6 7999.7 3827.3 3894.8 3895.1 3907.8 3908.3 4306.4 4324.0 4373.9 4420.8 4440.3 744.8 831.5 857.2 861.0 868.0 654.6 667.3 676.2 683.8 686.7 10847.5 11233.3 11917.5 11990.6 12076.8 640.5 641.3 650.1 657.6 658.8 4791.7 5334.0 5431.2 5568.6 5596.2 78507.6 96769.5 102437.0 105941.9 106698.5 79909.2 80111.6 80668.2 80861.0 98278.0 71913.6 72499.9 73343.6 73526.6 74705.3 9416.0 12348.8 12584.6 12820.6 12873.9 36775.8 37290.1 37514.1 38076.7 38678.0 47313.0 47881.0 47976.6 48165.6 49036.8 15978.4 16059.9 16401.8 16534.8 16535.8 14048.0 17588.7 18293.6 18313.6 18593.5 16401.9 16412.3 16562.9 16699.7 16875.7 16207.4 16278.4 16572.5 16729.5 16869.0 15629.1 16272.0 16323.6 16341.1 16540.7 9756.5 11724.3 11857.1 11962.3 12373.5 12397.1 12536.6 13465.0 13548.5 13816.8 09:10:13 INFO - PID 1646 | -------- Summary: end -------- 09:10:13 INFO - PID 1646 | 09:10:13 INFO - TEST-INFO | 1646: exit 0 09:10:13 INFO - TEST-OK | dromaeo_css | took 1113722ms 09:10:13 INFO - TEST-START | kraken 09:10:13 INFO - operating with platform_type : mac_ 09:10:13 INFO - Initialising browser for kraken test... 09:10:13 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/tmpq266OI/profile http://localhost:49219/getInfo.html) 09:10:14 INFO - PID 1659 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 09:10:16 INFO - PID 1659 | 2017-05-23 09:10:16.086 plugin-container[1660:10208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 09:10:16 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:16 INFO - PID 1659 | 2017-05-23 09:10:16.089 plugin-container[1660:10208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 09:10:16 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:16 INFO - PID 1659 | 2017-05-23 09:10:16.089 plugin-container[1660:10208] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:16 INFO - PID 1659 | __metrics Screen width/height:1600/1200 09:10:16 INFO - PID 1659 | colorDepth:24 09:10:16 INFO - PID 1659 | Browser inner width/height: 1024/691 09:10:16 INFO - PID 1659 | __metrics 09:10:17 INFO - TEST-INFO | 1659: exit 0 09:10:17 INFO - Browser initialized. 09:10:17 INFO - Running cycle 1/1 for kraken test... 09:10:17 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/T/tmpq266OI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:19 INFO - PID 1661 | 2017-05-23 09:10:19.283 plugin-container[1662:10326] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494b, name = 'com.apple.tsm.portname' 09:10:19 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:19 INFO - PID 1661 | 2017-05-23 09:10:19.284 plugin-container[1662:10326] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 09:10:19 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:19 INFO - PID 1661 | 2017-05-23 09:10:19.284 plugin-container[1662:10326] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:20 INFO - PID 1661 | 2017-05-23 09:10:20.047 plugin-container[1663:10373] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9443, name = 'com.apple.tsm.portname' 09:10:20 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:20 INFO - PID 1661 | 2017-05-23 09:10:20.048 plugin-container[1663:10373] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9617, name = 'com.apple.CFPasteboardClient' 09:10:20 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:20 INFO - PID 1661 | 2017-05-23 09:10:20.048 plugin-container[1663:10373] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:29 INFO - PID 1661 | RSS: Main: 255848448 09:10:29 INFO - PID 1661 | 09:10:31 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 09:10:32 INFO - PID 1661 | RSS: Main: 263741440 09:10:32 INFO - PID 1661 | 09:10:35 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 09:10:36 INFO - PID 1661 | RSS: Main: 271818752 09:10:36 INFO - PID 1661 | 09:10:39 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 09:10:40 INFO - PID 1661 | RSS: Main: 267476992 09:10:40 INFO - PID 1661 | 09:10:42 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 09:10:42 INFO - PID 1661 | RSS: Main: 268419072 09:10:42 INFO - PID 1661 | 09:10:45 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:10:45 INFO - PID 1661 | RSS: Main: 270172160 09:10:45 INFO - PID 1661 | 09:10:48 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 09:10:49 INFO - PID 1661 | RSS: Main: 279212032 09:10:49 INFO - PID 1661 | 09:10:52 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 09:10:53 INFO - PID 1661 | RSS: Main: 279797760 09:10:53 INFO - PID 1661 | 09:10:56 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 09:10:56 INFO - PID 1661 | RSS: Main: 278515712 09:10:56 INFO - PID 1661 | 09:10:58 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:10:58 INFO - PID 1661 | RSS: Main: 278200320 09:10:58 INFO - PID 1661 | 09:11:00 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:11:01 INFO - PID 1661 | RSS: Main: 278212608 09:11:01 INFO - PID 1661 | 09:11:03 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:11:04 INFO - PID 1661 | RSS: Main: 277831680 09:11:04 INFO - PID 1661 | 09:11:07 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:11:07 INFO - PID 1661 | RSS: Main: 278052864 09:11:07 INFO - PID 1661 | 09:11:11 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:11:11 INFO - PID 1661 | RSS: Main: 278274048 09:11:11 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 09:11:13 INFO - PID 1661 | RSS: Main: 280248320 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | __start_tp_report 09:11:13 INFO - PID 1661 | _x_x_mozilla_page_load 09:11:13 INFO - PID 1661 | _x_x_mozilla_page_load_details 09:11:13 INFO - PID 1661 | |i|pagename|runs| 09:11:13 INFO - PID 1661 | |0;ai-astar;104;114;115;114;114;114;113;114;115;115 09:11:13 INFO - PID 1661 | |1;audio-beat-detection;137;112;124;145;107;112;125;110;131;145 09:11:13 INFO - PID 1661 | |2;audio-dft;202;261;193;274;269;255;281;207;195;197 09:11:13 INFO - PID 1661 | |3;audio-fft;87;87;86;98;57;61;61;53;78;62 09:11:13 INFO - PID 1661 | |4;audio-oscillator;83;104;116;57;81;72;89;88;99;113 09:11:13 INFO - PID 1661 | |5;imaging-gaussian-blur;91;92;97;81;90;80;132;127;83;118 09:11:13 INFO - PID 1661 | |6;imaging-darkroom;97;122;149;152;97;97;98;98;97;99 09:11:13 INFO - PID 1661 | |7;imaging-desaturate;146;103;91;93;84;142;143;93;96;90 09:11:13 INFO - PID 1661 | |8;json-parse-financial;48;51;50;50;52;51;51;52;50;52 09:11:13 INFO - PID 1661 | |9;json-stringify-tinderbox;59;56;66;65;68;74;78;83;82;83 09:11:13 INFO - PID 1661 | |10;stanford-crypto-aes;82;83;82;83;83;87;83;86;86;81 09:11:13 INFO - PID 1661 | |11;stanford-crypto-ccm;156;146;150;137;138;162;148;158;184;137 09:11:13 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;206;236;187;187;182;184;186;220;184;179 09:11:13 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;66;67;70;68;69;64;65;68;67;71 09:11:13 INFO - PID 1661 | __end_tp_report 09:11:13 INFO - PID 1661 | __start_cc_report 09:11:13 INFO - PID 1661 | _x_x_mozilla_cycle_collect,928 09:11:13 INFO - PID 1661 | __end_cc_report 09:11:13 INFO - PID 1661 | __startTimestamp1495555873633__endTimestamp 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | ------- Summary: start ------- 09:11:13 INFO - PID 1661 | Number of tests: 14 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:3.29 (2.9%) stddev-sans-first:0.67 09:11:13 INFO - PID 1661 | Values: 104.0 114.0 115.0 114.0 114.0 114.0 113.0 114.0 115.0 115.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:128.00 stddev:14.41 (11.3%) stddev-sans-first:14.59 09:11:13 INFO - PID 1661 | Values: 137.0 112.0 124.0 145.0 107.0 112.0 125.0 110.0 131.0 145.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:233.40 Median:258.00 stddev:37.30 (14.5%) stddev-sans-first:37.80 09:11:13 INFO - PID 1661 | Values: 202.0 261.0 193.0 274.0 269.0 255.0 281.0 207.0 195.0 197.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:73.00 Median:82.00 stddev:15.90 (19.4%) stddev-sans-first:16.04 09:11:13 INFO - PID 1661 | Values: 87.0 87.0 86.0 98.0 57.0 61.0 61.0 53.0 78.0 62.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:82.00 stddev:18.35 (22.4%) stddev-sans-first:19.27 09:11:13 INFO - PID 1661 | Values: 83.0 104.0 116.0 57.0 81.0 72.0 89.0 88.0 99.0 113.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:86.50 stddev:19.36 (22.4%) stddev-sans-first:20.31 09:11:13 INFO - PID 1661 | Values: 91.0 92.0 97.0 81.0 90.0 80.0 132.0 127.0 83.0 118.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:97.00 stddev:22.39 (23.1%) stddev-sans-first:23.20 09:11:13 INFO - PID 1661 | Values: 97.0 122.0 149.0 152.0 97.0 97.0 98.0 98.0 97.0 99.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:108.10 Median:90.50 stddev:25.02 (27.6%) stddev-sans-first:22.46 09:11:13 INFO - PID 1661 | Values: 146.0 103.0 91.0 93.0 84.0 142.0 143.0 93.0 96.0 90.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.25 (2.5%) stddev-sans-first:0.87 09:11:13 INFO - PID 1661 | Values: 48.0 51.0 50.0 50.0 52.0 51.0 51.0 52.0 50.0 52.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:76.00 stddev:10.02 (13.2%) stddev-sans-first:9.58 09:11:13 INFO - PID 1661 | Values: 59.0 56.0 66.0 65.0 68.0 74.0 78.0 83.0 82.0 83.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.00 stddev:2.01 (2.4%) stddev-sans-first:2.05 09:11:13 INFO - PID 1661 | Values: 82.0 83.0 82.0 83.0 83.0 87.0 83.0 86.0 86.0 81.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:153.00 stddev:14.44 (9.4%) stddev-sans-first:15.23 09:11:13 INFO - PID 1661 | Values: 156.0 146.0 150.0 137.0 138.0 162.0 148.0 158.0 184.0 137.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.10 Median:187.00 stddev:19.16 (10.2%) stddev-sans-first:19.91 09:11:13 INFO - PID 1661 | Values: 206.0 236.0 187.0 187.0 182.0 184.0 186.0 220.0 184.0 179.0 09:11:13 INFO - PID 1661 | 09:11:13 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:2.17 (3.2%) stddev-sans-first:2.24 09:11:13 INFO - PID 1661 | Values: 66.0 67.0 70.0 68.0 69.0 64.0 65.0 68.0 67.0 71.0 09:11:13 INFO - PID 1661 | -------- Summary: end -------- 09:11:13 INFO - PID 1661 | 09:11:14 INFO - TEST-INFO | 1661: exit 0 09:11:14 INFO - TEST-OK | kraken | took 60615ms 09:11:14 INFO - SUITE-END | took 1174s 09:11:14 INFO - Completed test suite (00:19:34) 09:11:14 INFO - javascript benchmark 09:11:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7786.329943038986, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3525.4359302511593, 3568.3220188164246, 3596.586392564856, 3610.63893610639, 3611.801350925699, 5453.99101166284, 5862.501687356575, 6026.517878569714, 6237.781677641284, 6243.375662433755, 6154.507639388849, 6198.44214020738, 6207.255129384469, 6229.34591867853, 6263.217097862767, 5408.107662235727, 5434.347878254615, 5468.343798744147, 5481.917771233435, 5566.610337276392, 5354.732263386815, 5537.058700020986, 5540.2243685884005, 5551.278333816598, 5586.944130558703, 5492.066348720727, 5556.555475561966, 5557.6109672322955, 5583.441655834425, 5603.439656034405, 5400.540954018913, 5418.241446197536, 5438.83683489496, 5475.397706252309, 5538.280023596947, 4955.529224723646, 5105.668131571473, 5450.236966824647, 5473.616846820724, 5489.780408783644, 4720.645951553613, 6139.109829074804, 6237.812865614039, 6250.0, 6271.623702577815, 6042.667653279068, 6326.398992095744, 6609.603423794588, 6641.0, 6648.524678245061, 6486.318936511646, 6642.169728783902, 6733.4276586490205, 6749.392554670103, 6815.182178138605, 6310.684465776692, 6314.747410103636, 6350.587211831263, 6359.20509936258, 6374.235091788923, 6849.315068493111, 6945.131858517685, 6948.305169483011, 6955.6869940852785, 6957.9304206958295, 6343.714532846033, 6431.035344698239, 6452.161219041588, 6576.276609572943, 6595.868082638321, 5835.67903765297, 6692.096566963495, 6702.061711360413, 6743.629100399427, 6750.696218670171, 5934.465898069195, 6392.840178995562, 6639.800805975829, 6640.0703901453835, 6646.20245570525, 4877.853664390003, 5426.565874730012, 6560.245571759213, 6688.163979502562, 6700.363465470772, 5233.973830130825, 5347.625666203407, 5352.5450336721815, 5366.731663416814, 5371.838844834661, 4690.788914498856, 4712.740799256074, 4739.028499157734, 4845.854624361275, 4883.731394773251, 6269.96865015672, 6329.430351268408, 6357.904631430532, 6366.140571022946, 6381.8085457436355, 6807.455403567703, 6823.1812182538415, 6824.0, 6826.522143449911, 6828.5220034597105, 5480.424555421703, 6278.560500764903, 6316.178896743394, 6361.554691171574, 6382.936170638327, 5045.646804723708, 5937.614055086492, 6031.547633927473, 6062.181605483292, 6067.75728970845, 6909.930900690929, 6922.93077069243, 6972.1284839395075, 6973.093497845349, 6975.6163411012885, 6757.729690812313, 6790.626515541692, 6805.217399998865, 6812.386885180357, 6818.420434263143, 6694.531382803157, 6812.4890633203695, 6831.0, 6856.828579285558, 6896.310368963063, 6650.700718467778, 6652.800415987528, 6655.500837437115, 6672.499562532732, 6673.733050677918, 6708.8993665094085, 6763.154605674291, 6766.729330826909, 6793.762218322335, 6794.082798822095, 6340.524460665561, 6388.201474815648, 6469.223693156851, 6540.247871494648, 6565.80302590923, 5253.290805741329, 6465.741370345134, 6507.446867016167, 6568.671566421755, 6588.637624930675, 5674.546036317002, 5991.820245392645, 6266.248050234001, 6349.714262858275, 6386.712597933011, 6446.806595802133, 6530.934690653033, 6542.920418130955, 6551.312112228338, 6565.737370505126, 6174.845628859314, 6229.595076320024, 6299.401556852092, 6306.779262725782, 6355.300916899045], "value": 6069.86672754763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.6137601567106, 787.1420152034285, 824.0153017144511, 1207.2515040674782, 1259.6724851538609, 39501.0, 40008.39987400188, 40109.39562417507, 40156.99999999996, 40189.0, 39992.60007399925, 40207.79376618699, 40278.78466684331, 40399.0, 40540.59459405405, 6462.903056454157, 6991.475639327058, 8234.135415781346, 8418.747437576883, 8429.114942930995, 8467.872981905277, 8471.0, 8541.487510749344, 8572.399932004762, 8573.0, 8517.105703901094, 8549.40154189207, 8571.442856214357, 8572.571371431435, 8593.871091933626, 7199.784006479787, 8480.491170529787, 8572.828543429128, 8573.699920502815, 8574.614142363545, 8405.579721013986, 8460.323174146053, 8464.78838029048, 8483.406161568693, 8491.66033358665, 36930.0, 37144.628553714654, 37186.88439346824, 37225.44161837575, 37267.813660931795, 3623.4383670530847, 3656.963430365689, 3660.9749270204384, 3661.9450708239397, 3663.761855479412, 30197.849010755024, 30366.54450183249, 30498.84750576255, 30562.84718576393, 30650.54024189639, 2675.652165218529, 3092.2114860710376, 3093.041157241262, 3119.4229067622564, 3126.515390114536, 171370.0, 237369.25261494846, 241699.58300416946, 260371.9999999981, 285996.0, 46940.76529617398, 47225.5277447228, 47379.052418951425, 47451.5254847454, 47509.0, 48923.755381222865, 48948.26577601339, 49032.52902412933, 49089.77275568068, 49349.0130197394, 14282.0, 14432.134071955716, 14462.638434039232, 14462.855371446361, 14473.855261447461, 11650.941745291218, 11701.707457313636, 11776.94111529454, 11794.938455539042, 11806.0, 13380.933095334656, 13437.059405841497, 13551.322433878267, 13567.89697596874, 13720.519781807589, 6104.694765261719, 6130.846728831815, 6162.845928851725, 6169.629822210592, 6170.136180934673, 1023.3399457349967, 1056.4612047855649, 1058.756486008207, 1083.1551389915878, 1089.373610174153, 23371.7662823373, 24281.635775463383, 24587.754122458904, 24602.753972460403, 24647.01411943502, 1092.759592889563, 1142.354569668135, 1147.6499667601295, 1176.329492189461, 1179.604832381162, 12255.080868934865, 12533.623991280276, 14178.361973711208, 16771.2452939618, 16839.579010524834, 288899.999000091, 289976.0, 290923.0, 291062.0, 291173.0, 56255.43744562584, 56409.71795140998, 56813.715931420906, 57020.714896425256, 57088.42911570914, 1474.388128926505, 1497.0, 1540.306861912144, 1554.5958050906845, 1556.159673776148, 24666.50666986686, 27915.441691165655, 28331.575026375034, 28595.857020715597, 28718.138455846358, 32401.675983239867, 38554.22891542209, 38574.6142538571, 38588.6141138585, 38613.2277354457, 276283.0, 276287.0, 276683.0, 277416.0, 277677.0, 23709.762902370756, 29279.560806588343, 33997.980060598224, 35119.29761404809, 37197.07007324838, 37787.0, 46995.29507057329, 48875.26687099625, 48968.51031489782, 49246.50753492419, 29331.413371732873, 30639.395651368992, 31822.56798444029, 32612.83693581517, 33020.17449563684, 32297.838510808237, 32801.34397312088, 32942.0, 33008.3495825203, 33113.834430827694, 39798.60201398065, 40032.599674002886, 40089.39865902072, 40129.39805902856, 40386.79806600948, 30419.514141767555, 32107.678923211402, 33150.502742459365, 33157.66842331546, 33221.66778332186, 23999.0, 25596.0, 25857.078087399477, 26467.205983820517, 26734.732652674003], "value": 17749.126882671964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.7635988386329, 438.95829896159853, 441.25428026634995, 455.09890416974395, 459.3316724166338, 18614.534636634075, 19600.117994690238, 21979.23072692452, 22092.88953555234, 22118.778812211873, 16663.916680416613, 21669.783302166972, 21743.9128043598, 21914.0, 22012.248468941383, 5760.48155665991, 5932.317783454893, 5951.791687290945, 6002.129691194774, 6009.87980240395, 5745.454181852718, 5894.6168499047635, 5952.642841429506, 5971.761129554834, 5981.760729570812, 5881.8823623527505, 5916.26046744157, 5924.200232968558, 5936.762529498815, 5965.821025369224, 5665.348484924245, 5895.086261629454, 5953.047512398039, 5954.851128721773, 5955.272970838451, 5071.8985620287385, 5253.711045892474, 5295.205719142159, 5299.231611416361, 5303.469653034705, 26515.469690606078, 26532.46935061307, 26837.0, 26864.73135268642, 27207.591886121725, 2051.4050925231663, 2141.9785802141937, 2172.4351668566287, 2173.4240426287047, 2173.7608863025052, 17563.0, 17583.824161758348, 17703.822961770347, 18039.278428862828, 18092.90953545237, 2063.0, 2140.422086036777, 2159.373781603333, 2166.5991791518463, 2169.8155656769195, 148015.2599236997, 162964.0, 165666.0, 167105.0, 172380.13809930996, 12210.98518744415, 12500.749985000431, 12523.0, 12555.74888502243, 12643.43104560297, 15737.370505179893, 15866.52400427989, 15918.363265469483, 15930.761038584431, 15942.521724348288, 7732.4200684948855, 8302.958485207657, 8822.77943051416, 8826.911730882737, 8852.336074794417, 8345.040320363114, 8393.118722534045, 8404.41169118168, 8565.27195188416, 8589.52757598338, 6759.493038022168, 7512.32389084985, 7654.540727556365, 7736.381089512825, 7793.220677932161, 1860.8604354673182, 1927.2772710233662, 2016.4070384396457, 2195.9560808783735, 2375.4536456615074, 941.6798288581915, 960.8270511307892, 964.7395203295212, 966.6761634852263, 1011.595361855249, 17554.824451755318, 18905.7164142538, 19943.0, 20528.486787830425, 20557.074931628114, 994.1003391930315, 998.5805961496044, 1020.2144348851343, 1032.0711359776262, 1033.7674023344687, 7768.378529717722, 10455.372677639367, 10477.37135771856, 10492.895071049192, 10495.317804342692, 519448.0, 520729.0, 528289.0, 533388.0, 540104.0, 122307.38846305711, 123564.38217808853, 125073.0, 127238.0, 128586.35706821406, 1075.1506310015156, 1160.2690305107728, 1183.8875306845837, 1186.4067966016992, 1186.5372504723166, 17207.225674844412, 17741.556461088578, 17805.732914006043, 18015.459536213933, 18202.998835064198, 40273.798631006655, 53567.73216133894, 55719.0, 56381.71809141092, 56387.0, 64833.0, 70955.93566096616, 75060.0, 76526.6173669128, 76795.2320476788, 8433.283170930294, 8504.957475212585, 8552.57237138153, 8657.91342086571, 8880.111988801069, 11893.345865977455, 12051.939740301243, 13548.59354219375, 13758.243296618783, 14035.578932632037, 11846.466908989289, 11997.520099196283, 12116.697082573006, 12136.211146275464, 12176.330301833485, 10027.348222365523, 10956.26093476631, 11009.0, 11039.061679757311, 11279.7744045117, 10599.364038157735, 10840.566377345134, 12060.0, 12078.577249796215, 12200.633980980585, 6879.380855723009, 7494.700211991677, 8200.958995205225, 8737.301015918838, 8967.0, 11141.830107838885, 11558.711032224262, 11809.822852657046, 11923.284602923852, 11965.76068478643], "value": 10126.482451479485, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1277.6103288497009, 1467.589075058983, 1468.588795137362, 1470.7058588282339, 1471.227605507109, 11044.006039456448, 11379.772404551913, 11764.0, 11878.821817672742, 11885.702857428558, 11818.17272790905, 11844.289342639426, 11993.340366279852, 12021.699457513567, 12132.272063676186, 2055.3628375203734, 2508.49830033993, 2594.3514121469634, 2604.0625374865035, 2610.2169349195165, 2512.2588836293244, 2551.2473820222986, 2569.254916074336, 2569.409035921739, 2574.871256437171, 2539.7841183499427, 2556.7954563634867, 2587.1462417402213, 2589.04205443986, 2589.4303253284247, 2495.238952119603, 2519.5212909547126, 2526.2800101970943, 2548.9107881224068, 2553.374423266293, 2475.665785119004, 2543.26245388837, 2543.7710606045543, 2585.6380106785064, 2586.935326616831, 11626.186166968315, 11657.941710291394, 11753.412329383496, 11762.411879405996, 11775.293482391084, 6502.7398904043785, 6675.365840245169, 6712.060311556337, 6728.3271672832325, 6742.359475849787, 3451.3097380523745, 4014.658754005913, 4295.441592592974, 4303.634191093761, 4374.431323927901, 1209.1173443386376, 1222.1322860768864, 1240.5161986825146, 1244.116677159218, 1276.0748457368427, 14898.627534311621, 15202.239888005555, 16148.677026459523, 16299.755503667455, 16310.266038028318, 5351.5718742501285, 5439.592030597721, 5639.238702775155, 5680.943190568124, 5692.288463942007, 11605.535778568932, 11621.535138594361, 11643.0, 11645.767084658259, 11693.766124677459, 6438.45273151778, 6453.032045193165, 6456.128422662974, 6461.127747754087, 6464.127342808661, 6025.517958563304, 6135.263768347738, 6137.754489820357, 6227.0, 6261.123442718023, 3360.8655653773576, 3724.1434470071495, 3744.9514136041953, 4380.14587155508, 4412.933805992912, 1061.7345663584103, 1109.6615532262576, 1230.680023193976, 1266.6200139957975, 1298.9608313349472, 587.759018802289, 631.9557630965788, 770.5954373953607, 786.1195461083623, 807.6446363599997, 6028.5478589106, 6043.0, 6154.9384506154365, 6224.37756224374, 6245.718942647592, 478.6912441475229, 533.9065663508948, 544.300573762713, 552.4337554007094, 597.9760809567656, 3808.0, 4038.374052021941, 4213.6207741302815, 4274.102438487952, 4311.353297005474, 14302.284885755671, 14819.962602617714, 14835.703285934436, 14842.851571484363, 14893.478728244676, 13014.804777928339, 14298.070625409315, 14612.196329201995, 14748.41006359755, 14754.114753114633, 8403.663853445916, 8449.53527555978, 8490.660373584988, 8499.277561407349, 8508.787280317918, 5950.988331983486, 5977.491913187428, 6202.472789812736, 6816.386525212754, 6900.827479313058, 10527.263091583516, 12018.819717704417, 12040.518379265082, 12205.450754716237, 12231.633051008484, 12463.314517701614, 12555.87444125547, 13016.349182541024, 13142.80285795733, 13212.801807972695, 7874.606269686378, 8052.074011488754, 8073.596320184085, 8098.878516822135, 8108.0, 9143.908560914306, 9200.631974721204, 9281.118293762082, 9285.442873427615, 9294.349395542247, 8638.093000234885, 8922.776930576787, 8942.329325300498, 8946.971098323776, 8986.19124278818, 8634.266087382643, 8685.261752750834, 8756.0, 8773.42972706772, 8774.780630484289, 7775.183605721318, 7897.644605992629, 7993.840123197388, 8002.0, 8029.759107226792, 4989.35138432016, 5085.211792172219, 5925.4370834770625, 6019.458248757458, 6065.545084118797, 6006.579539432198, 6085.543584231112, 6178.351273116258, 6206.968965155326, 6323.019931910561], "value": 5150.166897585168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.89455794214535, 401.4379868184542, 402.7804846358734, 404.6034885811906, 412.6018392251475, 6154.69226538673, 6294.685265736712, 6294.716737746801, 6347.777827776029, 6362.236531616201, 6013.466566025668, 6016.699165041752, 6123.571350005502, 6177.135201071842, 6207.813765587029, 3869.6323849234277, 4713.670043096985, 4721.907150816221, 4727.290906364038, 4732.171869922767, 4579.450465944092, 4650.233111298789, 4724.078804633098, 4746.335513028179, 4754.263089221176, 3760.0975765816142, 3971.463852379935, 4663.487016428207, 4676.690526652543, 4699.6710230283725, 3787.5644300905337, 4683.742394168319, 4687.671862969593, 4691.812327506896, 4693.389859318267, 4132.896677583084, 4140.875773726793, 4141.7929103545, 4146.854860079913, 4173.08192197716, 5922.230110085661, 6041.167066346905, 6074.0, 6095.299040610343, 6100.664463454508, 4538.977305113453, 5251.973740131313, 5578.246936663539, 5589.161625756128, 5603.495685388335, 6447.516436267252, 6601.471882249409, 6639.900401493981, 6666.400063984657, 6670.7331706731675, 4702.764861756933, 4721.362616046824, 4725.456572494173, 4736.484325015997, 4758.119747846625, 6139.693015349215, 6199.535034872358, 6634.203895532567, 6971.233164351968, 7056.611886346249, 3705.6294370562728, 3729.7202709796875, 4404.568515232499, 4428.623566996841, 4432.113577284556, 3447.827608619559, 4010.9972506873282, 4158.897892058546, 4514.503404625489, 4515.81936722534, 4939.753012349368, 5339.410385962805, 5382.91925621116, 5390.137577987503, 5413.350397952271, 5163.690178589297, 6497.772577959749, 6683.899741503881, 6709.261981182066, 6718.798436046926, 1434.619825746178, 1451.9618472792004, 1487.4347747855961, 1528.7630417285338, 1537.1545649892514, 869.9826003480022, 992.8858181309098, 998.1715176403568, 999.9051039112161, 1016.5628779624684, 5341.919871201935, 6297.6155387144245, 6322.272938612026, 6350.968245158837, 6378.77674281398, 5467.863303417446, 6350.237971443364, 6887.5178737488895, 6893.827654308682, 6902.413294869892, 5503.3395992480355, 5759.005317235014, 6226.439620434183, 6310.7475700971445, 6315.873682526416, 3125.0, 3403.914902127417, 3605.5673319201865, 3606.6213047630145, 3607.7113830893463, 6017.916774980546, 6115.663638499836, 6251.218597675291, 6552.344765523505, 7079.610621415872, 5934.821955341346, 6163.660998645118, 6493.837654058591, 6749.966250168718, 7062.293770623, 6480.546361754727, 6843.657817109125, 6948.826279343057, 7140.285971402922, 7147.606881621561, 5764.654120752768, 6965.7213711453005, 6983.895241571279, 7012.158540975116, 7104.538205016657, 5741.311042674905, 6398.90401643985, 6504.959206526933, 6541.378569035934, 6977.895331569929, 4930.101910764453, 6380.680965951591, 7004.859902802017, 7104.786856394317, 7167.462440316893, 3922.9019274517223, 4460.8661740147845, 4474.776261186993, 4477.1493416252115, 4490.820367185407, 6138.877222455437, 6826.351496607815, 6892.140401375246, 7015.298470152944, 7054.294570542905, 6318.494520438446, 6336.271328797247, 6698.588508137164, 6742.5280230383405, 6773.525853190229, 6138.754449821957, 6436.67816609177, 6677.465802735866, 6721.361470660279, 6749.966250168718, 6020.939790602038, 6274.960637792645, 6550.148480697479, 6607.80176594703, 6625.933740662532, 6001.769637224445, 6201.007838745779, 6333.619982801047, 6744.393004629411, 6782.593044417351, 5370.597205209547, 6181.9690901547, 6429.97120460724, 6519.837004074746, 6540.313267106885], "value": 4798.257446266637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.8806684963829, 812.6261919517021, 833.4041160570191, 839.6893149534669, 865.6926790989198, 16099.275532601032, 16640.66718665626, 16672.583185420357, 18457.723134153, 19164.71252931207, 17621.55946101348, 18012.63974720508, 18065.187066582002, 18463.44609661709, 18784.62430751384, 1007.4056306778999, 1021.2953062386929, 1025.8820235672881, 1029.85067165261, 1031.6028329093315, 1005.4570531912794, 1010.6412223660604, 1013.3717095400825, 1019.0115587879744, 1019.108280254777, 1052.8420736889452, 1059.3379138038727, 1069.1393428290223, 1076.359566058194, 1081.5187241677456, 913.7989642278689, 1062.7077553672725, 1074.8226542620457, 1077.8922107789238, 1079.557381473596, 770.8650986077414, 780.3133242746347, 780.8516381887423, 795.3637090327715, 798.0383637716537, 16903.98576085439, 17236.310547578083, 17470.301187952467, 17492.988306344807, 17519.73720394195, 16828.990260584404, 17005.23476443563, 17044.31822727102, 17046.14769261532, 17097.0, 13801.51694690691, 14119.082259653193, 14424.855751442561, 14570.0, 14582.125072495684, 3549.8225088745717, 3598.226381328001, 3623.058004918714, 3628.0, 3640.308341415123, 98690.0, 111104.0, 111439.0, 112073.0, 113096.4345178277, 13472.932635336761, 13631.86368136306, 13744.587662370144, 13752.106113102616, 13789.724205516035, 24396.634050489258, 25294.0, 25540.0, 25869.74130258711, 25912.611310830354, 7840.176781437867, 7873.763787086396, 7922.801929951797, 7969.561674107979, 7999.720009799629, 3827.3110840048503, 3894.844206231776, 3895.0651843557343, 3907.78507182102, 3908.3160446921675, 4306.397104405386, 4323.978380108079, 4373.912521749611, 4420.84527041552, 4440.311751678495, 744.8361360500681, 831.4595512916652, 857.2028013946964, 861.0, 868.0321441592705, 654.6105067484839, 667.3460009190923, 676.2155899156954, 683.774354463026, 686.7459040155175, 10847.457627118612, 11233.32600044, 11917.523299068114, 11990.640280791591, 12076.758464830655, 640.5228105061666, 641.3394203969918, 650.0866282872572, 657.5528640524394, 658.785894584262, 4791.736454494967, 5334.0, 5431.21247419122, 5568.638038527563, 5596.188552659923, 78507.64477065697, 96769.5161524202, 102437.0, 105941.9405805932, 106698.46650766696, 79909.20090799134, 80111.59944200359, 80668.19331806607, 80861.0, 98278.0, 71913.64043179751, 72499.91250131252, 73343.63328183324, 73526.63236683782, 74705.25294747092, 9416.0, 12348.753024939631, 12584.622461326175, 12820.551280705131, 12873.871261287642, 36775.816120920295, 37290.067748305424, 37514.06214844651, 38076.66731664489, 38678.033049173995, 47313.0, 47881.0, 47976.56070317896, 48165.555033349054, 49036.75481622569, 15978.40215978393, 16059.919700401424, 16401.753973690167, 16534.83465165333, 16535.751963720795, 14048.0, 17588.73616895773, 18293.634127317644, 18313.633727325647, 18593.535161620526, 16401.917990409973, 16412.343506259615, 16562.917185413997, 16699.749503757695, 16875.662486749952, 16207.432739854048, 16278.430254941493, 16572.50282491527, 16729.49811505657, 16868.987860728397, 15629.140397277804, 16272.023678579322, 16323.59191020234, 16341.10123943147, 16540.66918661596, 9756.51217439111, 11724.296542207494, 11857.110716696456, 11962.282263064244, 12373.505059797868, 12397.070219733736, 12536.623901282976, 13464.990125740413, 13548.52580159729, 13816.792748108586], "value": 8265.0518666171, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1572.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 115.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 115.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 112.0, 124.0, 145.0, 107.0, 112.0, 125.0, 110.0, 131.0, 145.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 261.0, 193.0, 274.0, 269.0, 255.0, 281.0, 207.0, 195.0, 197.0], "value": 233.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 98.0, 57.0, 61.0, 61.0, 53.0, 78.0, 62.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 104.0, 116.0, 57.0, 81.0, 72.0, 89.0, 88.0, 99.0, 113.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 97.0, 81.0, 90.0, 80.0, 132.0, 127.0, 83.0, 118.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 122.0, 149.0, 152.0, 97.0, 97.0, 98.0, 98.0, 97.0, 99.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 103.0, 91.0, 93.0, 84.0, 142.0, 143.0, 93.0, 96.0, 90.0], "value": 108.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 51.0, 50.0, 50.0, 52.0, 51.0, 51.0, 52.0, 50.0, 52.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 66.0, 65.0, 68.0, 74.0, 78.0, 83.0, 82.0, 83.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 83.0, 83.0, 87.0, 83.0, 86.0, 86.0, 81.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 146.0, 150.0, 137.0, 138.0, 162.0, 148.0, 158.0, 184.0, 137.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 236.0, 187.0, 187.0, 182.0, 184.0, 186.0, 220.0, 184.0, 179.0], "value": 195.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 70.0, 68.0, 69.0, 64.0, 65.0, 68.0, 67.0, 71.0], "value": 67.5, "unit": "score"}]}]} 09:11:14 INFO - Return code: 0 09:11:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:11:14 INFO - # TBPL SUCCESS # 09:11:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:11:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:11:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:11:14 INFO - 2017-05-23 09:11:14.950 screenresolution[1668:10756] starting screenresolution argv=screenresolution get 09:11:14 INFO - 2017-05-23 09:11:14.953 screenresolution[1668:10756] Display 0: 1600x1200x32@60 09:11:14 INFO - 2017-05-23 09:11:14.959 screenresolution[1669:10759] starting screenresolution argv=screenresolution list 09:11:14 INFO - Available Modes on Display 0 09:11:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:11:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:11:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:11:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:11:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:11:15 INFO - Intel Iris: 09:11:15 INFO - Chipset Model: Intel Iris 09:11:15 INFO - Type: GPU 09:11:15 INFO - Bus: Built-In 09:11:15 INFO - VRAM (Dynamic, Max): 1536 MB 09:11:15 INFO - Vendor: Intel (0x8086) 09:11:15 INFO - Device ID: 0x0a2e 09:11:15 INFO - Revision ID: 0x0009 09:11:15 INFO - Displays: 09:11:15 INFO - Display: 09:11:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:11:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:11:15 INFO - Display Serial Number: 1600x1200 60 09:11:15 INFO - Main Display: Yes 09:11:15 INFO - Mirror: Off 09:11:15 INFO - Online: Yes 09:11:15 INFO - Rotation: Supported 09:11:15 INFO - Return code: 0 09:11:15 INFO - Running post-action listener: _resource_record_post_action 09:11:15 INFO - [mozharness: 2017-05-23 16:11:15.193533Z] Finished run-tests step (success) 09:11:15 INFO - Running post-run listener: _resource_record_post_run 09:11:15 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 38116352; Write bytes: 565968896; Read time: 409; Write time: 1774 09:11:15 INFO - TinderboxPrint: CPU usage
54.2% 09:11:15 INFO - TinderboxPrint: I/O read bytes / time
38,116,352 / 409 09:11:15 INFO - TinderboxPrint: I/O write bytes / time
565,968,896 / 1,774 09:11:15 INFO - TinderboxPrint: CPU idle
2,193.1 (45.8%) 09:11:15 INFO - TinderboxPrint: CPU system
964.9 (20.1%) 09:11:15 INFO - TinderboxPrint: CPU user
1,631.6 (34.1%) 09:11:15 INFO - TinderboxPrint: Swap in / out
283,459,584 / 0 09:11:15 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228631040; Write bytes: 227794944; Read time: 16790; Write time: 409 09:11:15 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 37161984; Write bytes: 319696896; Read time: 394; Write time: 1325 09:11:15 INFO - Running post-run listener: _upload_blobber_files 09:11:15 INFO - Blob upload gear active. 09:11:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:11:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:11:16 INFO - (blobuploader) - INFO - Open directory for files ... 09:11:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:11:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:11:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:17 INFO - (blobuploader) - INFO - Done attempting. 09:11:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:11:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:11:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:18 INFO - (blobuploader) - INFO - Done attempting. 09:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:11:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:19 INFO - (blobuploader) - INFO - Done attempting. 09:11:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:21 INFO - (blobuploader) - INFO - Done attempting. 09:11:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:21 INFO - Return code: 0 09:11:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e87363262955ce36433e47a252c80237fdecda1e8afc8173d5b62d6fb276b256914097560af559bcdc3980b3ef9e8e568a9c72ec765428fd7c92aa3a87d415e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ebe85aa58fbf4c70c0a5f2fa29be6c7c5525fd6d5629ddf650d69a7d178bee9b3a0ae969ad37d8c7d9217587dabde0fb7c0b902fe40505fb48167a49995cdea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ed77d120cf5ac7e7c5e2a078f7f52a8676da9054840c0ddf7024bc26491f9a371e8e87e2cccfc0f8c6b32fad01ab0e0cc11c1845046e4d3e9deaf3e8ff45dd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7504848538e5d9c28fdc36e77f22676ab5f40453a6ba06a8ab2c533d9423c7dab3587037893cc2f5d8fa0ca89ac202d403f96d41fca264f06d3b4ba390ee84"} 09:11:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:21 INFO - Contents: 09:11:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e87363262955ce36433e47a252c80237fdecda1e8afc8173d5b62d6fb276b256914097560af559bcdc3980b3ef9e8e568a9c72ec765428fd7c92aa3a87d415e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ebe85aa58fbf4c70c0a5f2fa29be6c7c5525fd6d5629ddf650d69a7d178bee9b3a0ae969ad37d8c7d9217587dabde0fb7c0b902fe40505fb48167a49995cdea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ed77d120cf5ac7e7c5e2a078f7f52a8676da9054840c0ddf7024bc26491f9a371e8e87e2cccfc0f8c6b32fad01ab0e0cc11c1845046e4d3e9deaf3e8ff45dd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7504848538e5d9c28fdc36e77f22676ab5f40453a6ba06a8ab2c533d9423c7dab3587037893cc2f5d8fa0ca89ac202d403f96d41fca264f06d3b4ba390ee84"} 09:11:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:21 INFO - Copying logs to upload dir... 09:11:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.976775 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-05-23 09:11:21.531386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:11:21.535445) ========= 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.xpeDpOny2o/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.Yknp0kXTqO/Listeners TMPDIR=/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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/1e87363262955ce36433e47a252c80237fdecda1e8afc8173d5b62d6fb276b256914097560af559bcdc3980b3ef9e8e568a9c72ec765428fd7c92aa3a87d415e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ebe85aa58fbf4c70c0a5f2fa29be6c7c5525fd6d5629ddf650d69a7d178bee9b3a0ae969ad37d8c7d9217587dabde0fb7c0b902fe40505fb48167a49995cdea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ed77d120cf5ac7e7c5e2a078f7f52a8676da9054840c0ddf7024bc26491f9a371e8e87e2cccfc0f8c6b32fad01ab0e0cc11c1845046e4d3e9deaf3e8ff45dd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7504848538e5d9c28fdc36e77f22676ab5f40453a6ba06a8ab2c533d9423c7dab3587037893cc2f5d8fa0ca89ac202d403f96d41fca264f06d3b4ba390ee84"} build_url:https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/SNiUiiIxRjiYwyshjir9fw/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/1e87363262955ce36433e47a252c80237fdecda1e8afc8173d5b62d6fb276b256914097560af559bcdc3980b3ef9e8e568a9c72ec765428fd7c92aa3a87d415e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ebe85aa58fbf4c70c0a5f2fa29be6c7c5525fd6d5629ddf650d69a7d178bee9b3a0ae969ad37d8c7d9217587dabde0fb7c0b902fe40505fb48167a49995cdea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ed77d120cf5ac7e7c5e2a078f7f52a8676da9054840c0ddf7024bc26491f9a371e8e87e2cccfc0f8c6b32fad01ab0e0cc11c1845046e4d3e9deaf3e8ff45dd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7504848538e5d9c28fdc36e77f22676ab5f40453a6ba06a8ab2c533d9423c7dab3587037893cc2f5d8fa0ca89ac202d403f96d41fca264f06d3b4ba390ee84"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:11:21.575388) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:11:21.575989) ========= 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.xpeDpOny2o/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.Yknp0kXTqO/Listeners TMPDIR=/var/folders/w5/3mbll1bn30g5ys4sq237f8fc00000w/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.004879 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:11:21.627193) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:11:21.627776) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:11:21.628319) ========= ========= Total master_lag: 0.19 =========