builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0230 starttime: 1496674383.34 results: success (0) revision: b7dfffc23174d96c9f85ce362f96c3751be7d113 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.341117) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.341626) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.341908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.367095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.367479) ========= 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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-05 07:53:03-- 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% 151M=0s 2017-06-05 07:53:03 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133636 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.524207) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.524540) ========= 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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.027338 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:03.573178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 07:53:03.573528) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b7dfffc23174d96c9f85ce362f96c3751be7d113 --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 b7dfffc23174d96c9f85ce362f96c3751be7d113 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.29Xg4cK9ry/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.WvTFNxHdPS/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-05 07:53:03,673 truncating revision to first 12 chars 2017-06-05 07:53:03,674 Setting DEBUG logging. 2017-06-05 07:53:03,674 attempt 1/10 2017-06-05 07:53:03,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b7dfffc23174?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 07:53:05,421 unpacking tar archive at: mozilla-central-b7dfffc23174/testing/mozharness/ program finished with exit code 0 elapsedTime=2.042923 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 07:53:05.638435) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:05.638782) ========= script_repo_revision: b7dfffc23174d96c9f85ce362f96c3751be7d113 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:05.639190) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:05.639464) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 07:53:05.658555) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-05 07:53:05.658957) ========= /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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:53:05 INFO - MultiFileLogger online at 20170605 07:53:05 in /builds/slave/test 07:53:05 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 07:53:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:53:05 INFO - {'append_to_log': False, 07:53:05 INFO - 'base_work_dir': '/builds/slave/test', 07:53:05 INFO - 'blob_upload_branch': 'Firefox', 07:53:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:53:05 INFO - 'branch': 'Firefox', 07:53:05 INFO - 'buildbot_json_path': 'buildprops.json', 07:53:05 INFO - 'config_files': ('talos/mac_config.py',), 07:53:05 INFO - 'default_actions': ('clobber', 07:53:05 INFO - 'read-buildbot-config', 07:53:05 INFO - 'download-and-extract', 07:53:05 INFO - 'populate-webroot', 07:53:05 INFO - 'create-virtualenv', 07:53:05 INFO - 'install', 07:53:05 INFO - 'setup-mitmproxy', 07:53:05 INFO - 'run-tests'), 07:53:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:53:05 INFO - 'download_minidump_stackwalk': True, 07:53:05 INFO - 'download_symbols': 'ondemand', 07:53:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:53:05 INFO - 'tooltool.py': '/tools/tooltool.py', 07:53:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:53:05 INFO - '/tools/misc-python/virtualenv.py')}, 07:53:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:53:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:53:05 INFO - 'gecko_profile': False, 07:53:05 INFO - 'gecko_profile_interval': 0, 07:53:05 INFO - 'installer_path': 'installer.exe', 07:53:05 INFO - 'log_level': 'info', 07:53:05 INFO - 'log_name': 'talos', 07:53:05 INFO - 'log_to_console': True, 07:53:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:53:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:53:05 INFO - 'opt_config_files': (), 07:53:05 INFO - 'pip_index': False, 07:53:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:53:05 INFO - 'cmd': ('bash', 07:53:05 INFO - '-c', 07:53:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:53:05 INFO - 'enabled': True, 07:53:05 INFO - 'halt_on_failure': False, 07:53:05 INFO - 'name': 'check_screen_resolution'},), 07:53:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:53:05 INFO - 'cmd': ('bash', 07:53:05 INFO - '-c', 07:53:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:53:05 INFO - 'enabled': True, 07:53:05 INFO - 'halt_on_failure': False, 07:53:05 INFO - 'name': 'check_screen_resolution'},), 07:53:05 INFO - 'run_cmd_checks_enabled': True, 07:53:05 INFO - 'suite': 'dromaeojs-e10s', 07:53:05 INFO - 'system_bits': '32', 07:53:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:53:05 INFO - 'title': 't-yosemite-r7-0230', 07:53:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:53:05 INFO - 'use_talos_json': True, 07:53:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:53:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:53:05 INFO - 'work_dir': 'build'} 07:53:05 INFO - [mozharness: 2017-06-05 14:53:05.873422Z] Running clobber step. 07:53:05 INFO - Running pre-action listener: _resource_record_pre_action 07:53:05 INFO - Running main action method: clobber 07:53:05 INFO - rmtree: /builds/slave/test/build 07:53:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:53:08 INFO - Running post-action listener: _resource_record_post_action 07:53:08 INFO - [mozharness: 2017-06-05 14:53:08.254362Z] Finished clobber step (success) 07:53:08 INFO - [mozharness: 2017-06-05 14:53:08.254493Z] Running read-buildbot-config step. 07:53:08 INFO - Running pre-action listener: _resource_record_pre_action 07:53:08 INFO - Running main action method: read_buildbot_config 07:53:08 INFO - Using buildbot properties: 07:53:08 INFO - { 07:53:08 INFO - "project": "", 07:53:08 INFO - "product": "firefox", 07:53:08 INFO - "who": "ryanvm@gmail.com", 07:53:08 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:53:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:53:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:53:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:53:08 INFO - "stage_platform": "macosx64", 07:53:08 INFO - "basedir": "/builds/slave/test", 07:53:08 INFO - "buildnumber": 86, 07:53:08 INFO - "platform": "yosemite_r7", 07:53:08 INFO - "parent_task_id": "FUTMD6MLRCS8yIu4_igkOQ", 07:53:08 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:53:08 INFO - "slavebuilddir": "test", 07:53:08 INFO - "taskId": "d6Wzx1wrStKN0jMnLwChjw", 07:53:08 INFO - "branch": "mozilla-central", 07:53:08 INFO - "script_repo_revision": "production", 07:53:08 INFO - "revision": "b7dfffc23174d96c9f85ce362f96c3751be7d113", 07:53:08 INFO - "slavename": "t-yosemite-r7-0230", 07:53:08 INFO - "repo_path": "mozilla-central" 07:53:08 INFO - } 07:53:08 INFO - Finding installer, test and symbols from parent task. 07:53:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d6Wzx1wrStKN0jMnLwChjw'}, attempt #1 07:53:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d6Wzx1wrStKN0jMnLwChjw'}, attempt #1 07:53:09 INFO - Task dependencies: VPEAKSnaQzme8-zTTE-vjg FUTMD6MLRCS8yIu4_igkOQ 07:53:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ'}, attempt #1 07:53:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:53:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/test_packages.json 07:53:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/target.crashreporter-symbols.zip 07:53:10 INFO - Running post-action listener: _resource_record_post_action 07:53:10 INFO - [mozharness: 2017-06-05 14:53:10.067788Z] Finished read-buildbot-config step (success) 07:53:10 INFO - [mozharness: 2017-06-05 14:53:10.067908Z] Running download-and-extract step. 07:53:10 INFO - Running pre-action listener: _resource_record_pre_action 07:53:10 INFO - Running main action method: download_and_extract 07:53:10 INFO - mkdir: /builds/slave/test/build/tests 07:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:10 INFO - trying https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:53:10 INFO - Downloading https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/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 07:53:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/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 07:53:10 INFO - Downloaded 1328 bytes. 07:53:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:53:10 INFO - Using the following test package requirements: 07:53:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:53:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:53:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:53:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:53:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'jsshell-mac.zip'], 07:53:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:53:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:53:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:53:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:53:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 07:53:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:53:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:53:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:53:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:53:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:53:10 INFO - Fetch https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:53:12 INFO - Content-Length response header: 57632095 07:53:12 INFO - Bytes received: 57632095 07:53:30 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 07:53:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:53:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:53:30 INFO - Fetch https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:53:32 INFO - Content-Length response header: 57632095 07:53:32 INFO - Bytes received: 57632095 07:53:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:53:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:53:49 INFO - Fetch https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:53:50 INFO - Content-Length response header: 14447425 07:53:50 INFO - Bytes received: 14447425 07:53:51 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')]} 07:53:51 INFO - trying https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:53:51 INFO - Downloading https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:53:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/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 07:53:53 INFO - Downloaded 67976032 bytes. 07:53:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:53:53 INFO - mkdir: /builds/slave/test/properties 07:53:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:53:53 INFO - Writing to file /builds/slave/test/properties/build_url 07:53:53 INFO - Contents: 07:53:53 INFO - build_url:https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:53:53 INFO - Running post-action listener: _resource_record_post_action 07:53:53 INFO - Running post-action listener: set_extra_try_arguments 07:53:53 INFO - [mozharness: 2017-06-05 14:53:53.206064Z] Finished download-and-extract step (success) 07:53:53 INFO - [mozharness: 2017-06-05 14:53:53.206207Z] Running populate-webroot step. 07:53:53 INFO - Running pre-action listener: _resource_record_pre_action 07:53:53 INFO - Running main action method: populate_webroot 07:53:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:53:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:53:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:53:53 INFO - '--tppagecycles', 07:53:53 INFO - '7'], 07:53:53 INFO - 'tests': ['tsvgm']}}, 07:53:53 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:53:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:53:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:53:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:53:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:53:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['damp', 'tps']}, 07:53:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'tests': ['damp', 'tps']}, 07:53:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['dromaeo_dom']}, 07:53:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:53:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:53:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:53:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:53:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['a11yr', 07:53:53 INFO - 'ts_paint', 07:53:53 INFO - 'tpaint', 07:53:53 INFO - 'sessionrestore', 07:53:53 INFO - 'sessionrestore_no_auto_restore', 07:53:53 INFO - 'tabpaint']}, 07:53:53 INFO - 'other-e10s': {'tests': ['a11yr', 07:53:53 INFO - 'ts_paint', 07:53:53 INFO - 'tpaint', 07:53:53 INFO - 'sessionrestore', 07:53:53 INFO - 'sessionrestore_no_auto_restore', 07:53:53 INFO - 'tabpaint']}, 07:53:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:53:53 INFO - 'ts_paint', 07:53:53 INFO - 'tpaint', 07:53:53 INFO - 'sessionrestore', 07:53:53 INFO - 'sessionrestore_no_auto_restore', 07:53:53 INFO - 'tabpaint']}, 07:53:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:53:53 INFO - 'ts_paint', 07:53:53 INFO - 'tpaint', 07:53:53 INFO - 'sessionrestore', 07:53:53 INFO - 'sessionrestore_no_auto_restore', 07:53:53 INFO - 'tabpaint']}, 07:53:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['a11yr', 07:53:53 INFO - 'ts_paint', 07:53:53 INFO - 'tpaint', 07:53:53 INFO - 'sessionrestore', 07:53:53 INFO - 'sessionrestore_no_auto_restore', 07:53:53 INFO - 'tabpaint']}, 07:53:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['a11yr', 07:53:53 INFO - 'ts_paint', 07:53:53 INFO - 'tpaint', 07:53:53 INFO - 'sessionrestore', 07:53:53 INFO - 'sessionrestore_no_auto_restore', 07:53:53 INFO - 'tabpaint']}, 07:53:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:53:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:53:53 INFO - 'bloom_basic_ref']}, 07:53:53 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 07:53:53 INFO - 'talos_options': ['--mitmproxy', 07:53:53 INFO - 'mitmproxy-recording-1.mp', 07:53:53 INFO - '--firstNonBlankPaint'], 07:53:53 INFO - 'tests': ['Quantum_1']}, 07:53:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['tsvgx', 07:53:53 INFO - 'tsvgr_opacity', 07:53:53 INFO - 'tart', 07:53:53 INFO - 'tscrollx', 07:53:53 INFO - 'cart', 07:53:53 INFO - 'tsvg_static']}, 07:53:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:53:53 INFO - 'tsvgr_opacity', 07:53:53 INFO - 'tart', 07:53:53 INFO - 'tscrollx', 07:53:53 INFO - 'cart', 07:53:53 INFO - 'tsvg_static']}, 07:53:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'talos_options': ['--disable-e10s'], 07:53:53 INFO - 'tests': ['tp5o']}, 07:53:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:53:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'talos_options': ['--disable-e10s', 07:53:53 INFO - '--xperf_path', 07:53:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:53:53 INFO - 'tests': ['tp5n']}, 07:53:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:53:53 INFO - 'talos_options': ['--xperf_path', 07:53:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:53:53 INFO - 'tests': ['tp5n']}}, 07:53:53 INFO - 'talos.zip': {'path': '', 07:53:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:53:53 INFO - Running post-action listener: _resource_record_post_action 07:53:53 INFO - [mozharness: 2017-06-05 14:53:53.228591Z] Finished populate-webroot step (success) 07:53:53 INFO - [mozharness: 2017-06-05 14:53:53.228736Z] Running create-virtualenv step. 07:53:53 INFO - Running pre-action listener: _resource_record_pre_action 07:53:53 INFO - Running main action method: create_virtualenv 07:53:53 INFO - Creating virtualenv /builds/slave/test/build/venv 07:53:53 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 07:53:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:53:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:53:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:53:53 INFO - Using real prefix '/tools/python27' 07:53:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:53:54 INFO - Installing distribute.............................................................................................................................................................................................done. 07:53:56 INFO - Installing pip.................done. 07:53:56 INFO - Return code: 0 07:53:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:53:57 INFO - Reading from file tmpfile_stdout 07:53:57 INFO - Output received: 07:53:57 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) 07:53:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:57 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:53:57 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 07:53:57 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 07:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:53:57 INFO - 'HOME': '/Users/cltbld', 07:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:53:57 INFO - 'LOGNAME': 'cltbld', 07:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:57 INFO - 'MOZ_NO_REMOTE': '1', 07:53:57 INFO - 'NO_EM_RESTART': '1', 07:53:57 INFO - 'PAGER': '/bin/cat', 07:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:57 INFO - 'PWD': '/builds/slave/test', 07:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:53:57 INFO - 'SHELL': '/bin/bash', 07:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:53:57 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:53:57 INFO - 'USER': 'cltbld', 07:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:57 INFO - 'XPC_FLAGS': '0x0', 07:53:57 INFO - 'XPC_SERVICE_NAME': '0', 07:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:57 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 07:53:57 INFO - Cleaning up... 07:53:57 INFO - Return code: 0 07:53:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:53:57 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:53:57 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 07:53:57 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 07:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:53:57 INFO - 'HOME': '/Users/cltbld', 07:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:53:57 INFO - 'LOGNAME': 'cltbld', 07:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:57 INFO - 'MOZ_NO_REMOTE': '1', 07:53:57 INFO - 'NO_EM_RESTART': '1', 07:53:57 INFO - 'PAGER': '/bin/cat', 07:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:57 INFO - 'PWD': '/builds/slave/test', 07:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:53:57 INFO - 'SHELL': '/bin/bash', 07:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:53:57 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:53:57 INFO - 'USER': 'cltbld', 07:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:57 INFO - 'XPC_FLAGS': '0x0', 07:53:57 INFO - 'XPC_SERVICE_NAME': '0', 07:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:53:57 INFO - Downloading/unpacking psutil>=3.1.1 07:53:57 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 07:53:57 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 07:53:57 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 07:53:57 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 07:53:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:53:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:53:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:53:59 INFO - Installing collected packages: psutil 07:53:59 INFO - Running setup.py install for psutil 07:53:59 INFO - building 'psutil._psutil_osx' extension 07:53: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 07:54: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 07:54: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 07:54: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 07:54:00 INFO - building 'psutil._psutil_posix' extension 07:54: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 07:54:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:54:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:54:00 INFO - ^ 07:54:00 INFO - 1 warning generated. 07:54: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 07:54:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:54:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:54:00 INFO - Successfully installed psutil 07:54:00 INFO - Cleaning up... 07:54:00 INFO - Return code: 0 07:54:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:54: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')]} 07:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:01 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')]} 07:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:01 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:01 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 07:54:01 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 07:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:01 INFO - 'HOME': '/Users/cltbld', 07:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:01 INFO - 'LOGNAME': 'cltbld', 07:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:01 INFO - 'MOZ_NO_REMOTE': '1', 07:54:01 INFO - 'NO_EM_RESTART': '1', 07:54:01 INFO - 'PAGER': '/bin/cat', 07:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:01 INFO - 'PWD': '/builds/slave/test', 07:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:01 INFO - 'SHELL': '/bin/bash', 07:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:01 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:01 INFO - 'USER': 'cltbld', 07:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:01 INFO - 'XPC_FLAGS': '0x0', 07:54:01 INFO - 'XPC_SERVICE_NAME': '0', 07:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:54:01 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 07:54:01 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 07:54:01 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 07:54:01 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 07:54:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:54:03 INFO - Installing collected packages: mozsystemmonitor 07:54:03 INFO - Running setup.py install for mozsystemmonitor 07:54:03 INFO - Successfully installed mozsystemmonitor 07:54:03 INFO - Cleaning up... 07:54:03 INFO - Return code: 0 07:54:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:54:03 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')]} 07:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:03 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')]} 07:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:03 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:03 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 07:54:03 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 07:54:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:03 INFO - 'HOME': '/Users/cltbld', 07:54:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:03 INFO - 'LOGNAME': 'cltbld', 07:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:03 INFO - 'MOZ_NO_REMOTE': '1', 07:54:03 INFO - 'NO_EM_RESTART': '1', 07:54:03 INFO - 'PAGER': '/bin/cat', 07:54:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:03 INFO - 'PWD': '/builds/slave/test', 07:54:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:03 INFO - 'SHELL': '/bin/bash', 07:54:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:03 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:03 INFO - 'USER': 'cltbld', 07:54:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:03 INFO - 'XPC_FLAGS': '0x0', 07:54:03 INFO - 'XPC_SERVICE_NAME': '0', 07:54:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:04 INFO - Downloading/unpacking jsonschema==2.5.1 07:54: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 07:54: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 07:54: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 07:54: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 07:54:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:54:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:54: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 07:54: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 07:54: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 07:54: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 07:54:05 INFO - Downloading functools32-3.2.3-2.tar.gz 07:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:54:05 INFO - warning: no files found matching '*.txt' 07:54:05 INFO - no previously-included directories found matching 'build' 07:54:05 INFO - no previously-included directories found matching 'dist' 07:54:05 INFO - no previously-included directories found matching '.git*' 07:54:05 INFO - Installing collected packages: jsonschema, functools32 07:54:05 INFO - Running setup.py install for functools32 07:54:06 INFO - warning: no files found matching '*.txt' 07:54:06 INFO - no previously-included directories found matching 'build' 07:54:06 INFO - no previously-included directories found matching 'dist' 07:54:06 INFO - no previously-included directories found matching '.git*' 07:54:06 INFO - Successfully installed jsonschema functools32 07:54:06 INFO - Cleaning up... 07:54:06 INFO - Return code: 0 07:54:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:54:06 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')]} 07:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:06 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')]} 07:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:06 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:06 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 07:54:06 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 07:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:06 INFO - 'HOME': '/Users/cltbld', 07:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:06 INFO - 'LOGNAME': 'cltbld', 07:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:06 INFO - 'MOZ_NO_REMOTE': '1', 07:54:06 INFO - 'NO_EM_RESTART': '1', 07:54:06 INFO - 'PAGER': '/bin/cat', 07:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:06 INFO - 'PWD': '/builds/slave/test', 07:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:06 INFO - 'SHELL': '/bin/bash', 07:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:06 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:06 INFO - 'USER': 'cltbld', 07:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:06 INFO - 'XPC_FLAGS': '0x0', 07:54:06 INFO - 'XPC_SERVICE_NAME': '0', 07:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:54:06 INFO - Cleaning up... 07:54:06 INFO - Return code: 0 07:54:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:54:06 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')]} 07:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:06 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')]} 07:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:06 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:06 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 07:54:06 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 07:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:06 INFO - 'HOME': '/Users/cltbld', 07:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:06 INFO - 'LOGNAME': 'cltbld', 07:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:06 INFO - 'MOZ_NO_REMOTE': '1', 07:54:06 INFO - 'NO_EM_RESTART': '1', 07:54:06 INFO - 'PAGER': '/bin/cat', 07:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:06 INFO - 'PWD': '/builds/slave/test', 07:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:06 INFO - 'SHELL': '/bin/bash', 07:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:06 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:06 INFO - 'USER': 'cltbld', 07:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:06 INFO - 'XPC_FLAGS': '0x0', 07:54:06 INFO - 'XPC_SERVICE_NAME': '0', 07:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:06 INFO - Downloading/unpacking blobuploader==1.2.4 07:54: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 07:54: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 07:54: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 07:54: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 07:54:08 INFO - Downloading blobuploader-1.2.4.tar.gz 07:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:54:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:54:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:08 INFO - Downloading docopt-0.6.1.tar.gz 07:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:54:09 INFO - Installing collected packages: blobuploader, requests, docopt 07:54:09 INFO - Running setup.py install for blobuploader 07:54:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:54:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:54:09 INFO - Running setup.py install for requests 07:54:09 INFO - Running setup.py install for docopt 07:54:09 INFO - Successfully installed blobuploader requests docopt 07:54:09 INFO - Cleaning up... 07:54:09 INFO - Return code: 0 07:54:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:09 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')]} 07:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:09 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')]} 07:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:09 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:09 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 07:54:09 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 07:54:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:09 INFO - 'HOME': '/Users/cltbld', 07:54:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:09 INFO - 'LOGNAME': 'cltbld', 07:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:09 INFO - 'MOZ_NO_REMOTE': '1', 07:54:09 INFO - 'NO_EM_RESTART': '1', 07:54:09 INFO - 'PAGER': '/bin/cat', 07:54:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:09 INFO - 'PWD': '/builds/slave/test', 07:54:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:09 INFO - 'SHELL': '/bin/bash', 07:54:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:09 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:09 INFO - 'USER': 'cltbld', 07:54:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:09 INFO - 'XPC_FLAGS': '0x0', 07:54:09 INFO - 'XPC_SERVICE_NAME': '0', 07:54:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-cfeIJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-pMN4Fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-LZqzWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-vEgS9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-PBp8ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9Y6J6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-e6oGAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-dXjwTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:10 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-tl9_0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Irlsfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-OtEYLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-CnSWiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-RRy1xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-J_jokj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-IaFlpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-GxFaPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Nq2m7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:54:11 INFO - Running setup.py install for manifestparser 07:54:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:54:12 INFO - Running setup.py install for mozcrash 07:54:12 INFO - Running setup.py install for mozdebug 07:54:12 INFO - Running setup.py install for mozdevice 07:54:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:54:12 INFO - Running setup.py install for mozfile 07:54:12 INFO - Running setup.py install for mozhttpd 07:54:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:54:12 INFO - Running setup.py install for mozinfo 07:54:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:54:12 INFO - Running setup.py install for mozInstall 07:54:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Running setup.py install for mozleak 07:54:13 INFO - Running setup.py install for mozlog 07:54:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Running setup.py install for moznetwork 07:54:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Running setup.py install for mozprocess 07:54:13 INFO - Running setup.py install for mozprofile 07:54:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:54:13 INFO - Running setup.py install for mozrunner 07:54:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:54:14 INFO - Running setup.py install for mozscreenshot 07:54:14 INFO - Running setup.py install for moztest 07:54:14 INFO - Running setup.py install for mozversion 07:54:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:54:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:54:14 INFO - Cleaning up... 07:54:14 INFO - Return code: 0 07:54:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:14 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:14 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 07:54:14 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 07:54:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:14 INFO - 'HOME': '/Users/cltbld', 07:54:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:14 INFO - 'LOGNAME': 'cltbld', 07:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:14 INFO - 'MOZ_NO_REMOTE': '1', 07:54:14 INFO - 'NO_EM_RESTART': '1', 07:54:14 INFO - 'PAGER': '/bin/cat', 07:54:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:14 INFO - 'PWD': '/builds/slave/test', 07:54:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:14 INFO - 'SHELL': '/bin/bash', 07:54:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:14 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:14 INFO - 'USER': 'cltbld', 07:54:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:14 INFO - 'XPC_FLAGS': '0x0', 07:54:14 INFO - 'XPC_SERVICE_NAME': '0', 07:54:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:14 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9u72Da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:14 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)) 07:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:14 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-QMkZh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:14 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)) 07:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:14 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-wB1icu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-L1wLLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-g2r7Qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-JFPbuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-P9yMcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-GjiSLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-gsM8h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-q0puK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9hGWYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:15 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)) 07:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:15 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Yvn9YZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:16 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)) 07:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-gQM3FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:16 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)) 07:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-8CHhfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:16 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)) 07:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-zO4xFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:16 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)) 07:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-4B6VZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:16 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)) 07:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-jmTIY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:16 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)) 07:54:16 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)) 07:54:16 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)) 07:54:16 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)) 07:54:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:17 INFO - Downloading blessings-1.6.tar.gz 07:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:54:18 INFO - Installing collected packages: blessings 07:54:18 INFO - Running setup.py install for blessings 07:54:18 INFO - Successfully installed blessings 07:54:18 INFO - Cleaning up... 07:54:18 INFO - Return code: 0 07:54:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:54:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:18 INFO - Reading from file tmpfile_stdout 07:54:18 INFO - Current package versions: 07:54:18 INFO - blessings == 1.6 07:54:18 INFO - blobuploader == 1.2.4 07:54:18 INFO - docopt == 0.6.1 07:54:18 INFO - functools32 == 3.2.3-2 07:54:18 INFO - jsonschema == 2.5.1 07:54:18 INFO - manifestparser == 1.1 07:54:18 INFO - mozInstall == 1.12 07:54:18 INFO - mozcrash == 1.0 07:54:18 INFO - mozdebug == 0.1 07:54:18 INFO - mozdevice == 0.51 07:54:18 INFO - mozfile == 1.2 07:54:18 INFO - mozhttpd == 0.7 07:54:18 INFO - mozinfo == 0.9 07:54:18 INFO - mozleak == 0.1 07:54:18 INFO - mozlog == 3.4 07:54:18 INFO - moznetwork == 0.27 07:54:18 INFO - mozprocess == 0.25 07:54:18 INFO - mozprofile == 0.28 07:54:18 INFO - mozrunner == 6.13 07:54:18 INFO - mozscreenshot == 0.1 07:54:18 INFO - mozsystemmonitor == 0.3 07:54:18 INFO - moztest == 0.8 07:54:18 INFO - mozversion == 1.4 07:54:18 INFO - psutil == 3.1.1 07:54:18 INFO - requests == 1.2.3 07:54:18 INFO - wsgiref == 0.1.2 07:54:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:18 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:18 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 07:54:18 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 07:54:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:18 INFO - 'HOME': '/Users/cltbld', 07:54:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:18 INFO - 'LOGNAME': 'cltbld', 07:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:18 INFO - 'MOZ_NO_REMOTE': '1', 07:54:18 INFO - 'NO_EM_RESTART': '1', 07:54:18 INFO - 'PAGER': '/bin/cat', 07:54:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:18 INFO - 'PWD': '/builds/slave/test', 07:54:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:18 INFO - 'SHELL': '/bin/bash', 07:54:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:18 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:18 INFO - 'USER': 'cltbld', 07:54:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:18 INFO - 'XPC_FLAGS': '0x0', 07:54:18 INFO - 'XPC_SERVICE_NAME': '0', 07:54:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:18 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)) 07:54:18 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)) 07:54:18 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)) 07:54:18 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)) 07:54:18 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)) 07:54:18 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)) 07:54:18 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)) 07:54:18 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)) 07:54:18 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)) 07:54:18 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:20 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:54:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:54:20 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)) 07:54:20 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)) 07:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:54:20 INFO - Installing collected packages: marionette-driver 07:54:20 INFO - Running setup.py install for marionette-driver 07:54:20 INFO - Successfully installed marionette-driver 07:54:20 INFO - Cleaning up... 07:54:20 INFO - Return code: 0 07:54:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:20 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 0x10614cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062cc1560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, '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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 07:54:20 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 07:54:20 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 07:54:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:20 INFO - 'HOME': '/Users/cltbld', 07:54:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:20 INFO - 'LOGNAME': 'cltbld', 07:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:20 INFO - 'MOZ_NO_REMOTE': '1', 07:54:20 INFO - 'NO_EM_RESTART': '1', 07:54:20 INFO - 'PAGER': '/bin/cat', 07:54:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:20 INFO - 'PWD': '/builds/slave/test', 07:54:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:20 INFO - 'SHELL': '/bin/bash', 07:54:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:20 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:20 INFO - 'USER': 'cltbld', 07:54:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:20 INFO - 'XPC_FLAGS': '0x0', 07:54:20 INFO - 'XPC_SERVICE_NAME': '0', 07:54:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:54:20 INFO - Cleaning up... 07:54:20 INFO - Return code: 0 07:54:20 INFO - Running post-action listener: _resource_record_post_action 07:54:20 INFO - Running post-action listener: _start_resource_monitoring 07:54:20 INFO - Starting resource monitoring. 07:54:20 INFO - [mozharness: 2017-06-05 14:54:20.831013Z] Finished create-virtualenv step (success) 07:54:20 INFO - [mozharness: 2017-06-05 14:54:20.831971Z] Running install step. 07:54:20 INFO - Running pre-action listener: _resource_record_pre_action 07:54:20 INFO - Running main action method: install 07:54:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:21 INFO - Reading from file tmpfile_stdout 07:54:21 INFO - Detecting whether we're running mozinstall >=1.0... 07:54:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:54:21 INFO - Reading from file tmpfile_stdout 07:54:21 INFO - Output received: 07:54:21 INFO - Usage: mozinstall [options] installer 07:54:21 INFO - Options: 07:54:21 INFO - -h, --help show this help message and exit 07:54:21 INFO - -d DEST, --destination=DEST 07:54:21 INFO - Directory to install application into. [default: 07:54:21 INFO - "/builds/slave/test"] 07:54:21 INFO - --app=APP Application being installed. [default: firefox] 07:54:21 INFO - mkdir: /builds/slave/test/build/application 07:54:21 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'] 07:54:21 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 07:54:42 INFO - Reading from file tmpfile_stdout 07:54:42 INFO - Output received: 07:54:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:54:42 INFO - Running post-action listener: _resource_record_post_action 07:54:42 INFO - [mozharness: 2017-06-05 14:54:42.922313Z] Finished install step (success) 07:54:42 INFO - [mozharness: 2017-06-05 14:54:42.922444Z] Running setup-mitmproxy step. 07:54:42 INFO - Running pre-action listener: _resource_record_pre_action 07:54:42 INFO - Running main action method: setup_mitmproxy 07:54:42 INFO - Skipping: mitmproxy is not required 07:54:42 INFO - Running post-action listener: _resource_record_post_action 07:54:42 INFO - [mozharness: 2017-06-05 14:54:42.923128Z] Finished setup-mitmproxy step (success) 07:54:42 INFO - [mozharness: 2017-06-05 14:54:42.923266Z] Running run-tests step. 07:54:42 INFO - Running pre-action listener: _resource_record_pre_action 07:54:42 INFO - Running main action method: run_tests 07:54:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:54:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:54:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:54:42 INFO - 2017-06-05 07:54:42.937 screenresolution[1628:7561] starting screenresolution argv=screenresolution get 07:54:42 INFO - 2017-06-05 07:54:42.940 screenresolution[1628:7561] Display 0: 1600x1200x32@60 07:54:42 INFO - 2017-06-05 07:54:42.946 screenresolution[1629:7563] starting screenresolution argv=screenresolution list 07:54:42 INFO - Available Modes on Display 0 07:54:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:54:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:54:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:54:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:54:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:54:43 INFO - Intel Iris: 07:54:43 INFO - Chipset Model: Intel Iris 07:54:43 INFO - Type: GPU 07:54:43 INFO - Bus: Built-In 07:54:43 INFO - VRAM (Dynamic, Max): 1536 MB 07:54:43 INFO - Vendor: Intel (0x8086) 07:54:43 INFO - Device ID: 0x0a2e 07:54:43 INFO - Revision ID: 0x0009 07:54:43 INFO - Displays: 07:54:43 INFO - Display: 07:54:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:54:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:54:43 INFO - Display Serial Number: 1600x1200 60 07:54:43 INFO - Main Display: Yes 07:54:43 INFO - Mirror: Off 07:54:43 INFO - Online: Yes 07:54:43 INFO - Rotation: Supported 07:54:43 INFO - Return code: 0 07:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:54:43 INFO - Python 2.7.3 07:54:43 INFO - Return code: 0 07:54:43 INFO - grabbing minidump binary from tooltool 07:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:43 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 0x1060d5c60>, '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 0x105e6acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e93be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:54:43 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 07:54:43 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 07:54:43 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 07:54:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:54:43 INFO - Return code: 0 07:54:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:54:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:54:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:54:43 INFO - ENV: RUST_BACKTRACE is now 1 07:54:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:54:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:54:43 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/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 07:54:43 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/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0230 --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 07:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29Xg4cK9ry/Render', 07:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:43 INFO - 'HOME': '/Users/cltbld', 07:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:43 INFO - 'LOGNAME': 'cltbld', 07:54:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:54:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:43 INFO - 'MOZ_NO_REMOTE': '1', 07:54:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:54:43 INFO - 'NO_EM_RESTART': '1', 07:54:43 INFO - 'PAGER': '/bin/cat', 07:54:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:43 INFO - 'PWD': '/builds/slave/test', 07:54:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:43 INFO - 'RUST_BACKTRACE': '1', 07:54:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:54:43 INFO - 'SHELL': '/bin/bash', 07:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WvTFNxHdPS/Listeners', 07:54:43 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 07:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:43 INFO - 'USER': 'cltbld', 07:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:43 INFO - 'XPC_FLAGS': '0x0', 07:54:43 INFO - 'XPC_SERVICE_NAME': '0', 07:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:43 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/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 07:54:43 INFO - mozversion application_buildid: 20170605064034 07:54:43 INFO - mozversion application_changeset: b7dfffc23174d96c9f85ce362f96c3751be7d113 07:54:43 INFO - mozversion application_display_name: Nightly 07:54:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:54:43 INFO - mozversion application_name: Firefox 07:54:43 INFO - mozversion application_remotingname: firefox 07:54:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:54:43 INFO - mozversion application_vendor: Mozilla 07:54:43 INFO - mozversion application_version: 55.0a1 07:54:43 INFO - mozversion platform_buildid: 20170605064034 07:54:43 INFO - mozversion platform_changeset: b7dfffc23174d96c9f85ce362f96c3751be7d113 07:54:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:54:43 INFO - mozversion platform_version: 55.0a1 07:54:43 INFO - using testdate: 1496674483 07:54:43 INFO - actual date: 1496674483 07:54:43 INFO - starting webserver on 'localhost:49229' 07:54:43 INFO - SUITE-START | Running 2 tests 07:54:43 INFO - TEST-START | dromaeo_css 07:54:43 INFO - operating with platform_type : mac_ 07:54:43 INFO - Initialising browser for dromaeo_css test... 07:54:43 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpO7VtQE/profile 07:54:43 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 07:55:17 INFO - TEST-INFO | 1639: killed by SIGTERM 07:55:17 INFO - Browser initialized. 07:55:17 INFO - Running cycle 1/1 for dromaeo_css test... 07:55:17 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpO7VtQE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:19 INFO - PID 1649 | 2017-06-05 07:55:19.962 plugin-container[1650:8138] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 07:55:19 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:19 INFO - PID 1649 | 2017-06-05 07:55:19.963 plugin-container[1650:8138] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa013, name = 'com.apple.CFPasteboardClient' 07:55:19 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:19 INFO - PID 1649 | 2017-06-05 07:55:19.964 plugin-container[1650:8138] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:55:20 INFO - PID 1649 | 2017-06-05 07:55:20.325 plugin-container[1651:8184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 07:55:20 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:20 INFO - PID 1649 | 2017-06-05 07:55:20.325 plugin-container[1651:8184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 07:55:20 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:20 INFO - PID 1649 | 2017-06-05 07:55:20.325 plugin-container[1651:8184] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:55:29 INFO - PID 1649 | RSS: Main: 268517376 07:55:29 INFO - PID 1649 | 07:58:31 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 07:58:33 INFO - PID 1649 | RSS: Main: 266235904 07:58:33 INFO - PID 1649 | 08:01:35 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 08:01:35 INFO - PID 1649 | RSS: Main: 281063424 08:01:35 INFO - PID 1649 | 08:04:37 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 08:04:38 INFO - PID 1649 | RSS: Main: 280891392 08:04:38 INFO - PID 1649 | 08:07:39 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 08:07:40 INFO - PID 1649 | RSS: Main: 285683712 08:07:40 INFO - PID 1649 | 08:10:43 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 08:10:44 INFO - PID 1649 | RSS: Main: 285827072 08:10:44 INFO - PID 1649 | 08:13:45 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 08:13:46 INFO - PID 1649 | RSS: Main: 287989760 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | __start_tp_report 08:13:46 INFO - PID 1649 | _x_x_mozilla_page_load 08:13:46 INFO - PID 1649 | _x_x_mozilla_page_load_details 08:13:46 INFO - PID 1649 | |i|pagename|runs| 08:13:46 INFO - PID 1649 | |0;dojo.html;3044.1932887784733;3048.7103725146103;3603.189282411456;3650.0874781304674;3654.232611151658;6133.426475724757;6144.293406258282;6241.438270555649;6264.498840092793;6284.057391391282;6211.50307975362;6217.5958562693395;6234.625922444645;6313.305536390993;6328.145700330466;4961.578265847389;4995.35060442142;5040.823571175009;5434.7826086956575;5480.479354461339;5516.338039435253;5610;5614.719264036802;5631.267935168423;5659.179418984245;5382.569394448435;5442.210879422501;5479.945200548023;5485.451454854522;5506.889862202774;5509.338879334466;5522.6134170608075;5525.834224973257;5539.9169012464845;5555;5448.645838020516;5461.836144915659;5505.146702261156;5506;5534.1975413564805;4405.405270288502;6167.321594624587;6209.565330426872;6233.906491402633;6306.180196574416;6700.396964273239;6734.225564060147;6746.865062698718;6757.4457874688605;6767.932320676779;6718.126643536358;6827.385535301847;6829.965850170767;6850.38346548813;6857.211420686635;6257.124002639634;6262.1546091278005;6295.3389894060465;6370.203724534434;6376.936230637727;6902.206246281641;6962.582245065312;6980.685869135901;6992.440604751608;7006.544574602634;6245.6252624842655;6410.294867564564;6434;6588.110605068255;6613.537052406382;6728.562643428161;6825.385715285549;6847.897281540781;6857.451403887677;6865.519413640997;6478;6499.2850786413455;6748.29142939994;6749.8650026999185;6762.425193858478;6632.336766323426;6649.634270115093;6744.359285867933;6771.356721111487;6772.356626120511;4149.8340066397;5207.3230480037355;5355.785768569292;5390.838274851761;5437.102878025082;4012.959870401317;4027.154297597479;4730.787114579852;4819.421669399694;4873.780679869415;6206.906896396557;6314.936850631527;6318.020706790455;6347.174867267226;6400.807471106433;6358.872822543616;6846.383825455633;6849.075374824335;6852.383285504328;6858.828529286808;6221.3156552779155;6288.27684816252;6337.302896681362;6371.108044873721;6387.137736405619;4890.535399137077;5743.483086522233;5905.350411454737;5971.104334349882;5979.701014949235;5828.94171058284;6973.651317434108;6994.68523916425;7006.229314775269;7022.192447868459;6768.187817461936;6825.249222585611;6838.487113466609;6847.1783385994;6864.588124712333;6738.797836064926;6826.385625293548;6988.9301106990315;6992.965035174791;7018.2630823762765;6634.767783127568;6661.966690166712;6689.2307384651385;6711.429528490133;6728.259891412039;6362.45919096882;6840.521163518705;6858;6860.725570977105;6867.828304292432;5211.5049070338255;6464.385883341075;6558.409743123142;6634.668266586555;6641;6593.076969224217;6609.405153536205;6624.271330153776;6632.237292711207;6653.467722582085;5321.600879934098;6291.1192433058495;6296.307406185408;6396.26442959047;6479.8056058318325;6583.374579414948;6607.306232845482;6613.603183808987;6626.304238055128;6648.5346025777735;6387.393197646216;6437.937740272802;6441;6445.935540644534;6455.838604034937 08:13:46 INFO - PID 1649 | |1;ext.html;798.9880151797724;813.5810057820223;842.8904242448483;1258.767128081305;1326.8208791813101;37844;38385.04037399066;38421.231575368474;38452.807735961316;38468;32546.67453325466;38385.808070959676;38539.61460385395;38628.80685596575;38683;7799.5710235937;8223.383246256524;8463.873041904377;8474.23731864132;8476.110008449117;7178.3898368638465;8276;8365.372597055215;8381.62282697277;8399.732072340465;8337.66649334029;8404.369672274574;8406.579671016425;8408.411411201218;8413.200745929127;8322.62548185333;8348.582570871493;8374.916250837505;8396.034456037572;8409.537475438849;8091.5145091294125;8157.592120393957;8161.8367632647605;8166.754997350089;8175.386845986574;36021.21777213381;36130.277394451965;36147;36362;36679.26641467182;3552.875649352286;3572.142685755426;3575.731820113476;3575.8212089395424;3576.678098971105;29375.118746437427;29732.55401168984;29757.851210743807;29796;30039;2641.960370594443;3060.4491191585507;3069.6469905960876;3076.6769489203534;3085.490894002487;249166;251786;283526.99999999796;303072;304954;46378.76810615925;46568.30147547789;46636;46679;46775.53224467712;48102.03795924062;48676;48723.512764872605;48729.756351218726;49735;14287.357068931922;14369.497067602584;14443.277836108153;14491.420343186366;14515.491957781427;11501.309921404743;11518.712032199095;11601.187916845909;11618.593349232906;11634.360110193851;11219.382933938712;13909.374078166507;13930.721385572231;13931.373088210852;13936;5311.2033195020285;6055.364186760415;6193.848956492751;6234.719437625317;6258.123862659231;998.4758002048983;1002.5538635307219;1004.382304882492;1043.8590790243372;1044.6970378590277;23016.769832301463;29184.124476265744;29833.10500684983;30265.24336891595;30861.765529379023;1065.4679736672904;1066.3601838896755;1112.8108221602347;1113.832925061231;1116.3525155409843;11741.530338786353;11933;12180.198554332554;12427.627171184879;12428.316442595564;296727;298424;299398;300738;302192;52585.47414525882;53274.668133296975;53574.7321263399;53662;53666.73166634142;1404.2838152542276;1476.6382236351947;1482.5033613739301;1484.85893840085;1495.4990078323665;22624.434389140402;23266.767332327137;23608.881955590114;23805.166819161248;23881;37564.62435375611;37703.434448482745;37760.62239377571;37823;38093.80953095217;268077;269030;269046;269696.65151674114;270381.6480917583;32394.676053239167;34102.658973410944;34230.82884585561;34418.31163376669;34427.4835877457;43536;43702.78148609364;43720;43724.56275437205;43789.56210437855;20212.696809547575;26558.7344126564;28099.157025289274;29701.554476682435;30274;24474.63288050645;28647.713522864506;30054.699453006066;30083.548746768378;30544.389112217188;36405.72579959689;36463.635363646026;36603;36622.45066323954;36646.816765916;28968;29612;30140.397192056476;30595.54106688357;30739;23503.764962350157;23619;23842.284731458083;23851.284461466184;23909.92405341729 08:13:46 INFO - PID 1649 | |2;jquery.html;424.5670860844649;429.6562749800159;436.98033588488505;446.6627696089452;448.5962633629733;14658.926705366459;15417.614559636015;18834.90582547087;22301.776982230193;22506.66240006401;19234.61530769384;23220;24139.517209655798;24304.392390190253;24599.508009839792;6131.785387511449;6301.842453938642;6309;6312.779052733155;6331.588446750947;6204.751809927598;6253.874922501547;6288.371162883721;6300.905486417708;6307.211598550181;6019.428154325354;6085.235281768282;6091.57358984869;6169.383061693818;6250.656213908233;6143.477804386633;6184.103305020769;6199.256089269294;6199.783007594758;6205.7517699291975;5469;5512.310961129859;5519.089350257203;5523.613347065707;5524.723763811793;26899.813043000024;27717.029903953466;28064.719352806416;28161.436771264664;28229.85885070582;2211.9225827096134;2238.1942500699734;2247.5617254635354;2257.9613377846213;2263.0721404224355;18126.818731812644;18416.90791546034;18647.720284195748;18698.62602747951;18711.251549938122;2111.567128738605;2133.4666333416644;2175.7062796522423;2183.1376606240606;2198.5822693688147;174870.12564937095;175440;177672;203762;205876.94123058728;12684;12865.678358040937;13247.602571922858;13250.138740981805;13255.933720331337;16715.247813848408;16717.665646686997;16724.331026759035;16748.748768768477;16766.664666706598;7484.476086673881;8953;9073.047330030251;9086.818263634823;9152;8678.6528538859;8719.476831390137;8723.781905452415;8724.56377181151;8837.955810220908;7744.947588888949;8204.917950820534;8313.667453301921;8344.916550834536;8389.119142490074;2249.5500899819776;2254.132159118751;2258.1419060756807;2258.254775923941;2446.718627357877;948.2177203806887;962.0009620009697;963.7038183642989;993.2600212841479;1002.8646132772128;19260;19958;20452.261557767757;20459.59080818375;21341.359759207247;978.3836183204536;1010.5806090472372;1023.3501726403787;1029.1509504658686;1044.738815296176;10399.376037437778;10531.420771857622;10536.367817930948;10909.290896091728;11278.436078196057;552258;552583;561079;564369;571840;120025.39987300182;142518;142787;143535.2823235898;143957;1141.08713029576;1154.8556430446195;1173.3898372846072;1175.5591653130077;1191.1542804608653;16774.832251677326;18153.364632237808;18201.362952296608;18981.715274271173;19273.325433609756;64056.718865623356;67122.32877671161;67556.66221668861;68158;68609;84131.57934210535;84182;84283.57858210916;84838.72741908753;87022;8081.9191808083515;8621.396502244783;9593.664221752206;9724.708258751965;9885.357451765612;13791.2414817182;17073.82926170772;18026.459206223833;18301.084945752926;18391;14894.329755160827;15028;15046.022008569407;15135.697286053997;15181.874537564529;12450.377481126088;12905.483780648668;13721.862781372058;13773.517926872511;13825.585232443043;15163.848361516244;15188.848111518744;15373.69252614919;15440;15450.605156200358;10140.391576505139;10309.948450258;10312.690619281433;10314.896851031694;10322.793544129225;14612.926935365254;14633.122012679274;14677.045992010486;14706.41174353014;14796.48212312607 08:13:46 INFO - PID 1649 | |3;mootools.html;1262.1921969939235;1362.3937347880194;1415.4621243927313;1423.2314550142924;1440.279860069965;11685.883141168586;11866.7626647467;12152.696182595428;12160.27038377698;12560.937195314033;10616.946915265433;12118.151729378947;12165.391730413467;12301.876981230207;12370.134090613661;2043.2950632031925;2136.3270569770552;2227.0757635581213;2268.6823844661762;2276.931692049241;2217.8225741940605;2240.7423146338137;2250.4261413339577;2251.7748225177515;2251.88740562972;2212.7565967743535;2217.090992692996;2217.545403192342;2226.4100013496436;2237.049254067023;2104.8000439958178;2189.7591264960843;2195.978040219601;2203.60335139674;2205.746339171;1927.5952050069502;2013.506690860734;2182.7708090650412;2188.67169924511;2193.4187440328324;9483.573239204252;11938.403079845973;12137.817932731015;12139.210951288138;12161.635150945485;6394.273546142515;6645.833854153637;6647.700853461557;6648.268690444047;6680.164979377578;3343.933121337584;4167.708260421772;4199.265128602513;4276.893077673052;4289.2922667759785;954.2985905359556;1196.4735516372768;1201.1471854982972;1223.2782658231597;1223.363850797589;16825.66348673032;16931.830681693147;17058;17061.146942652817;17066.82933170665;5409.83770486886;5444.646098003617;5470.769076957732;5537.916931246034;5676.403977582377;12498.50005999768;12507.812382814265;12523.937380313222;12542.1847579907;12542.184757990884;6310.5267104967315;6395.648239346788;6433;6437.549371544041;6460.612363258219;5967.313758917693;5982.4017598239825;6092.329843717188;6105.603135796247;6113.052476866093;4467.24056910326;4498.235299999008;4563.634909207255;4587.7017993830295;4594.264917613166;1293.8318018657515;1309.1490531154825;1316.8748968847945;1323.8808507234396;1328.860469650692;676.7563677076249;763.324502716543;804.8712206047121;828.5277391886567;835.9331253499705;6425.775097871552;6702.899456508157;6710.396064354231;6826.385625293748;6859.5541289816;583.2388732703862;610.1823556434399;611.4496952742568;612.5712001598898;618.9257289125334;4379.1241751649795;4823.734694591761;4928.088303663833;5024.773885175176;5260.789568417221;15116.924415377853;15326.770098448533;15371;15655.84344156544;15674.764878526832;14064.578062658136;15369.615759605877;15483.922580387027;15657.060576365455;15686.764698529303;8625.39622226438;8698.652053917773;8801.427907186013;8802.03177650458;8815.559222038872;5595.63628364155;6985.860282794417;7395.524103671266;7515.962420187864;7692.96145020415;13515.729685406433;13712.108712933627;13794.93102534481;13812.964027698165;14039;14342.107223190527;14343.705007198798;14522.273886305853;14560.344784484936;14651.633709157357;9548.37935534188;9784.070513301225;9937.602495900082;9951.701448956543;9954.651587194414;10951.45242737844;10962.56149754001;11024.559017639205;11041.558337666725;11070.391128487689;8471.57642117904;8718.389712720049;8803.29573634095;8966.282697384064;8981.730548083307;7575.393968482398;8158.632861521127;8380.784786206088;8736;8801.647934082564;8382.874256886029;8483.278921291512;8505.872411913702;8519.3184545235;8535.274501667427;6157.199564056644;6167.5682702210415;6549.541532092708;6661.034150048119;6738.191417029988;5664.461876121762;6312.684365781784;6695.12963314766;6753.3246675332075;6761.932380676327 08:13:46 INFO - PID 1649 | |4;prototype.html;383.80809595202396;401.29973196771635;402.54914495764746;409.57199726286035;411.2617850957531;5404.540614047805;6422.9357706422925;6553.934460655392;6554.573952693072;6621.404073633372;5100.33695619569;6383.712732927018;6391.520635952299;6405.167328247321;6407.807765767023;3359.5800524934384;3766.9434958475645;4076.714629975888;4347.978260108695;4665.976670116653;3848.883823691126;4676.508966558514;4678.087772884289;4689.859304220879;4701.647376446763;4423.623991960671;4521.75651695783;4662.300654901758;4666.346711460398;4672.229082201433;4659.930101048487;4674.976625116887;4676;4686.859394218179;4689.906201875978;3822.4075268333454;4013.5986401359924;4029.355303151511;4033.3949907513816;4039.838406463738;5655.755733738571;6156.815295541096;6299.9370006299805;6333.55665103443;6342.302346741854;5573.303337082864;5583.190437386556;5583.388694362181;5589.127642239854;5597.944020559783;6854.931450685529;6863.691133898987;6870.488492531635;6878.002689609979;6881.13985751781;4553.544645535453;4560;4560.977195114003;4562.406887104688;4575.8560359910025;5586.217929489874;5887.764489420418;6838.350356716153;7280.2719728027305;7363.447741419415;4623.468301145412;4653.115068657454;4660.161170989254;4665.090307390026;4670.719756814602;4177.26897792885;4720.740359280273;4740.1230772307235;4750.881227969328;4752.691075080109;5524.585905812246;5529.419410961791;5576.86057848557;5693.231413759172;5829.912551311733;5692.345380281238;6914.377706006484;6956.130483689539;6964.756233531802;6982.476314276348;1513.8486151384773;1793.7309403589566;1802.5583731985748;1803.666321730484;1807.530042189014;1198.8801119887942;1250.8936740376987;1258.937053147339;1268.9974919813428;1269.853966793812;5490.917636235459;6035;6507.362490259909;6516.837079073061;6542.901856472156;7200.567965922061;7206.891896621555;7210.134783825974;7233.132024157135;7240.855182896313;5421.430749771296;6467.900456922334;6474.4496717778575;6474.611523308617;6809;3118.440779610195;3642.7450078494253;3649.1242101895355;3742.8689995850013;3761.6802571781705;6344.651044192521;6494.902576461357;7332.936724174965;7406.7037318506655;7420.85158296831;6014.398560143951;6866.073080134218;7402.666879990414;7407;7487.925120748723;6744.954532047541;6762.425193858577;7335.376492998155;7346.375558077516;7403.888941665987;5329.600279979095;6133.659291041877;6288;6548.443382312556;7194.892076618959;5924.822255332347;7099.254578269208;7102.680379383043;7121.643917804193;7148.606826624586;6762.459003279824;7104.751333703295;7229.855402891808;7313.524620899624;7405.555666660018;4158.604932531404;5129.743512824419;5145.511176438233;5162.380514338303;5164.366173251594;7165.820854478679;7219.318147744583;7238.565686058853;7251.456140789356;7251.963740181265;6077.027675572064;6940.826479338057;6943.444524438134;7007.334303241184;7028.20780700918;5236.39781425141;6072.386929776776;6948.6178260196175;6960.37356637905;7000.124984376953;6766.1880574329425;6811.250762416229;6815.284395138439;6833.829154270984;6836.829079273058;5062.493750624908;6033.87932241344;6166.335089525893;6854.074699915447;6977.476689248225;5663.943360566342;6440.42383046785;6742.6628668567355;6771.720400569506;6789.490788190807 08:13:46 INFO - PID 1649 | |5;yui.html;713.2724620886698;751.4476859508261;834.5535138700797;864.7449002544249;865.195368307474;17215.913920430412;17636.118194090293;17674.823251767477;17721.291148354067;17727.202275897584;12782.041346898997;12931.159474634142;13041.869581304185;14956.999999999973;15986.76019859703;963.595289978209;976.1848856205041;983.208517143699;984.394597322645;985.2265971212599;981.3719219699398;989.3420875118038;991.3110388280148;992.7121134870915;993.3195760903768;997.1175509673918;1030.7886883188928;1037.9792404151913;1039.8024375368693;1041.8489319048733;1033.152814691953;1035.6116456328878;1035.7617747917946;1038.2680210451651;1038.3510306058713;758.0865057605627;767.48578452437;775.1318523253935;775.3215936055951;778.0080397493185;17555.29778808846;17562.209700563504;17608;17639.73540396895;17937.820621793744;17993.55016124594;18005.7299140513;18224.906505609706;18502.2599096036;18518;14466.85533144676;14835.406583736638;15013.774793378108;15075.54773356801;15138.545843624599;3559.501669766235;3575.106223444139;3576.7853928764357;3587.3722098632898;3780.8298626561873;146461;149159.25420372936;150046.24976875156;150603;152558;13582.185068895898;13731.24478153711;13766.793498097537;13852.515161969484;13885.514007009908;26889;27076.32309192286;27130.914763409637;27342.316442089108;27672.169834904984;7984.40116991228;8014.278714915686;8059.274665280153;8064.435489515678;8067.1529489402765;4006.979965100214;4036.656884164879;4083.0608959938786;4094.0788322627172;4100.979495102505;4412.382266482695;4416.823327066945;4480.798364073625;4532.773361331921;4533.659975501851;857.3484152044491;887.2236792806294;896.1665650944553;897.0625696147541;897.1701176411923;544.8528897197734;639.114825966031;647.3364801078932;670.597641415147;681.1723755636925;8983.101689830964;10906.963838435337;11224.831627525593;11259.211855170222;11880.208151084487;566.9935863841631;640.7789312687115;643.5913195121038;661.5732852310233;673.4309508465338;5238.449962753933;5416.83749487516;5744.684042377626;5801.984652685763;6428.8071357859335;118239.81760182288;121939.39030304791;122896.38551807184;125234;142878;89808.5509572461;113870.29194562088;114352;114685.4265728666;118663.62672746669;97021.02978970262;100501.49749251206;101513.49243253736;101989;103870;11313.434328283389;12080.51677932873;12405;12987.337595792651;13182.472701092232;44539;44891.326630101226;45058.54941450675;45383.54616453793;45419.772901135286;58025.70987145037;60552.69723651354;60810.78378432495;61133.38866611455;62312.37687623066;13921.721565568834;16977.405790797264;17325.913370433067;17392.565185870455;17407;18760.718589220898;18762.624747505248;18975.33586324472;19059.809401905804;19175.52061198481;17468.650626987645;17583.64832703313;17589;17699.469015929542;18046.097695114928;16863.072531010912;17123.315067397663;17127.400540981005;17207.483775486755;18493;17144.971301721936;17342.653146936736;18026.549336266697;18343.449696509128;18369.265229390676;11655.76688466243;11802.527898884291;12647.936760316139;12756.808647870104;12936.41786119608;11603.6518904433;13182.670433239246;14314.355853986805;14836.258187090818;14860.479883204036 08:13:46 INFO - PID 1649 | __end_tp_report 08:13:46 INFO - PID 1649 | __start_cc_report 08:13:46 INFO - PID 1649 | _x_x_mozilla_cycle_collect,450 08:13:46 INFO - PID 1649 | __end_cc_report 08:13:46 INFO - PID 1649 | __startTimestamp1496675626031__endTimestamp 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | ------- Summary: start ------- 08:13:46 INFO - PID 1649 | Number of tests: 6 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6159.54 Median:6387.39 stddev:818.16 (12.8%) stddev-sans-first:783.32 08:13:46 INFO - PID 1649 | Values: 3044.2 3048.7 3603.2 3650.1 3654.2 6133.4 6144.3 6241.4 6264.5 6284.1 6211.5 6217.6 6234.6 6313.3 6328.1 4961.6 4995.4 5040.8 5434.8 5480.5 5516.3 5610.0 5614.7 5631.3 5659.2 5382.6 5442.2 5479.9 5485.5 5506.9 5509.3 5522.6 5525.8 5539.9 5555.0 5448.6 5461.8 5505.1 5506.0 5534.2 4405.4 6167.3 6209.6 6233.9 6306.2 6700.4 6734.2 6746.9 6757.4 6767.9 6718.1 6827.4 6830.0 6850.4 6857.2 6257.1 6262.2 6295.3 6370.2 6376.9 6902.2 6962.6 6980.7 6992.4 7006.5 6245.6 6410.3 6434.0 6588.1 6613.5 6728.6 6825.4 6847.9 6857.5 6865.5 6478.0 6499.3 6748.3 6749.9 6762.4 6632.3 6649.6 6744.4 6771.4 6772.4 4149.8 5207.3 5355.8 5390.8 5437.1 4013.0 4027.2 4730.8 4819.4 4873.8 6206.9 6314.9 6318.0 6347.2 6400.8 6358.9 6846.4 6849.1 6852.4 6858.8 6221.3 6288.3 6337.3 6371.1 6387.1 4890.5 5743.5 5905.4 5971.1 5979.7 5828.9 6973.7 6994.7 7006.2 7022.2 6768.2 6825.2 6838.5 6847.2 6864.6 6738.8 6826.4 6988.9 6993.0 7018.3 6634.8 6662.0 6689.2 6711.4 6728.3 6362.5 6840.5 6858.0 6860.7 6867.8 5211.5 6464.4 6558.4 6634.7 6641.0 6593.1 6609.4 6624.3 6632.2 6653.5 5321.6 6291.1 6296.3 6396.3 6479.8 6583.4 6607.3 6613.6 6626.3 6648.5 6387.4 6437.9 6441.0 6445.9 6455.8 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:43366.21 Median:31628.22 stddev:74071.45 (234.2%) stddev-sans-first:74210.29 08:13:46 INFO - PID 1649 | Values: 799.0 813.6 842.9 1258.8 1326.8 37844.0 38385.0 38421.2 38452.8 38468.0 32546.7 38385.8 38539.6 38628.8 38683.0 7799.6 8223.4 8463.9 8474.2 8476.1 7178.4 8276.0 8365.4 8381.6 8399.7 8337.7 8404.4 8406.6 8408.4 8413.2 8322.6 8348.6 8374.9 8396.0 8409.5 8091.5 8157.6 8161.8 8166.8 8175.4 36021.2 36130.3 36147.0 36362.0 36679.3 3552.9 3572.1 3575.7 3575.8 3576.7 29375.1 29732.6 29757.9 29796.0 30039.0 2642.0 3060.4 3069.6 3076.7 3085.5 249166.0 251786.0 283527.0 303072.0 304954.0 46378.8 46568.3 46636.0 46679.0 46775.5 48102.0 48676.0 48723.5 48729.8 49735.0 14287.4 14369.5 14443.3 14491.4 14515.5 11501.3 11518.7 11601.2 11618.6 11634.4 11219.4 13909.4 13930.7 13931.4 13936.0 5311.2 6055.4 6193.8 6234.7 6258.1 998.5 1002.6 1004.4 1043.9 1044.7 23016.8 29184.1 29833.1 30265.2 30861.8 1065.5 1066.4 1112.8 1113.8 1116.4 11741.5 11933.0 12180.2 12427.6 12428.3 296727.0 298424.0 299398.0 300738.0 302192.0 52585.5 53274.7 53574.7 53662.0 53666.7 1404.3 1476.6 1482.5 1484.9 1495.5 22624.4 23266.8 23608.9 23805.2 23881.0 37564.6 37703.4 37760.6 37823.0 38093.8 268077.0 269030.0 269046.0 269696.7 270381.6 32394.7 34102.7 34230.8 34418.3 34427.5 43536.0 43702.8 43720.0 43724.6 43789.6 20212.7 26558.7 28099.2 29701.6 30274.0 24474.6 28647.7 30054.7 30083.5 30544.4 36405.7 36463.6 36603.0 36622.5 36646.8 28968.0 29612.0 30140.4 30595.5 30739.0 23503.8 23619.0 23842.3 23851.3 23909.9 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:37975.26 Median:12269.99 stddev:96543.10 (786.8%) stddev-sans-first:96772.76 08:13:46 INFO - PID 1649 | Values: 424.6 429.7 437.0 446.7 448.6 14658.9 15417.6 18834.9 22301.8 22506.7 19234.6 23220.0 24139.5 24304.4 24599.5 6131.8 6301.8 6309.0 6312.8 6331.6 6204.8 6253.9 6288.4 6300.9 6307.2 6019.4 6085.2 6091.6 6169.4 6250.7 6143.5 6184.1 6199.3 6199.8 6205.8 5469.0 5512.3 5519.1 5523.6 5524.7 26899.8 27717.0 28064.7 28161.4 28229.9 2211.9 2238.2 2247.6 2258.0 2263.1 18126.8 18416.9 18647.7 18698.6 18711.3 2111.6 2133.5 2175.7 2183.1 2198.6 174870.1 175440.0 177672.0 203762.0 205876.9 12684.0 12865.7 13247.6 13250.1 13255.9 16715.2 16717.7 16724.3 16748.7 16766.7 7484.5 8953.0 9073.0 9086.8 9152.0 8678.7 8719.5 8723.8 8724.6 8838.0 7744.9 8204.9 8313.7 8344.9 8389.1 2249.6 2254.1 2258.1 2258.3 2446.7 948.2 962.0 963.7 993.3 1002.9 19260.0 19958.0 20452.3 20459.6 21341.4 978.4 1010.6 1023.4 1029.2 1044.7 10399.4 10531.4 10536.4 10909.3 11278.4 552258.0 552583.0 561079.0 564369.0 571840.0 120025.4 142518.0 142787.0 143535.3 143957.0 1141.1 1154.9 1173.4 1175.6 1191.2 16774.8 18153.4 18201.4 18981.7 19273.3 64056.7 67122.3 67556.7 68158.0 68609.0 84131.6 84182.0 84283.6 84838.7 87022.0 8081.9 8621.4 9593.7 9724.7 9885.4 13791.2 17073.8 18026.5 18301.1 18391.0 14894.3 15028.0 15046.0 15135.7 15181.9 12450.4 12905.5 13721.9 13773.5 13825.6 15163.8 15188.8 15373.7 15440.0 15450.6 10140.4 10309.9 10312.7 10314.9 10322.8 14612.9 14633.1 14677.0 14706.4 14796.5 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:7224.68 Median:4238.08 stddev:4712.83 (111.2%) stddev-sans-first:4704.76 08:13:46 INFO - PID 1649 | Values: 1262.2 1362.4 1415.5 1423.2 1440.3 11685.9 11866.8 12152.7 12160.3 12560.9 10616.9 12118.2 12165.4 12301.9 12370.1 2043.3 2136.3 2227.1 2268.7 2276.9 2217.8 2240.7 2250.4 2251.8 2251.9 2212.8 2217.1 2217.5 2226.4 2237.0 2104.8 2189.8 2196.0 2203.6 2205.7 1927.6 2013.5 2182.8 2188.7 2193.4 9483.6 11938.4 12137.8 12139.2 12161.6 6394.3 6645.8 6647.7 6648.3 6680.2 3343.9 4167.7 4199.3 4276.9 4289.3 954.3 1196.5 1201.1 1223.3 1223.4 16825.7 16931.8 17058.0 17061.1 17066.8 5409.8 5444.6 5470.8 5537.9 5676.4 12498.5 12507.8 12523.9 12542.2 12542.2 6310.5 6395.6 6433.0 6437.5 6460.6 5967.3 5982.4 6092.3 6105.6 6113.1 4467.2 4498.2 4563.6 4587.7 4594.3 1293.8 1309.1 1316.9 1323.9 1328.9 676.8 763.3 804.9 828.5 835.9 6425.8 6702.9 6710.4 6826.4 6859.6 583.2 610.2 611.4 612.6 618.9 4379.1 4823.7 4928.1 5024.8 5260.8 15116.9 15326.8 15371.0 15655.8 15674.8 14064.6 15369.6 15483.9 15657.1 15686.8 8625.4 8698.7 8801.4 8802.0 8815.6 5595.6 6985.9 7395.5 7516.0 7693.0 13515.7 13712.1 13794.9 13813.0 14039.0 14342.1 14343.7 14522.3 14560.3 14651.6 9548.4 9784.1 9937.6 9951.7 9954.7 10951.5 10962.6 11024.6 11041.6 11070.4 8471.6 8718.4 8803.3 8966.3 8981.7 7575.4 8158.6 8380.8 8736.0 8801.6 8382.9 8483.3 8505.9 8519.3 8535.3 6157.2 6167.6 6549.5 6661.0 6738.2 5664.5 6312.7 6695.1 6753.3 6761.9 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5522.85 Median:6053.69 stddev:1734.70 (28.7%) stddev-sans-first:1696.14 08:13:46 INFO - PID 1649 | Values: 383.8 401.3 402.5 409.6 411.3 5404.5 6422.9 6553.9 6554.6 6621.4 5100.3 6383.7 6391.5 6405.2 6407.8 3359.6 3766.9 4076.7 4348.0 4666.0 3848.9 4676.5 4678.1 4689.9 4701.6 4423.6 4521.8 4662.3 4666.3 4672.2 4659.9 4675.0 4676.0 4686.9 4689.9 3822.4 4013.6 4029.4 4033.4 4039.8 5655.8 6156.8 6299.9 6333.6 6342.3 5573.3 5583.2 5583.4 5589.1 5597.9 6854.9 6863.7 6870.5 6878.0 6881.1 4553.5 4560.0 4561.0 4562.4 4575.9 5586.2 5887.8 6838.4 7280.3 7363.4 4623.5 4653.1 4660.2 4665.1 4670.7 4177.3 4720.7 4740.1 4750.9 4752.7 5524.6 5529.4 5576.9 5693.2 5829.9 5692.3 6914.4 6956.1 6964.8 6982.5 1513.8 1793.7 1802.6 1803.7 1807.5 1198.9 1250.9 1258.9 1269.0 1269.9 5490.9 6035.0 6507.4 6516.8 6542.9 7200.6 7206.9 7210.1 7233.1 7240.9 5421.4 6467.9 6474.4 6474.6 6809.0 3118.4 3642.7 3649.1 3742.9 3761.7 6344.7 6494.9 7332.9 7406.7 7420.9 6014.4 6866.1 7402.7 7407.0 7487.9 6745.0 6762.4 7335.4 7346.4 7403.9 5329.6 6133.7 6288.0 6548.4 7194.9 5924.8 7099.3 7102.7 7121.6 7148.6 6762.5 7104.8 7229.9 7313.5 7405.6 4158.6 5129.7 5145.5 5162.4 5164.4 7165.8 7219.3 7238.6 7251.5 7252.0 6077.0 6940.8 6943.4 7007.3 7028.2 5236.4 6072.4 6948.6 6960.4 7000.1 6766.2 6811.3 6815.3 6833.8 6836.8 5062.5 6033.9 6166.3 6854.1 6977.5 5663.9 6440.4 6742.7 6771.7 6789.5 08:13:46 INFO - PID 1649 | 08:13:46 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:24658.66 Median:18284.18 stddev:37135.16 (203.1%) stddev-sans-first:37195.81 08:13:46 INFO - PID 1649 | Values: 713.3 751.4 834.6 864.7 865.2 17215.9 17636.1 17674.8 17721.3 17727.2 12782.0 12931.2 13041.9 14957.0 15986.8 963.6 976.2 983.2 984.4 985.2 981.4 989.3 991.3 992.7 993.3 997.1 1030.8 1038.0 1039.8 1041.8 1033.2 1035.6 1035.8 1038.3 1038.4 758.1 767.5 775.1 775.3 778.0 17555.3 17562.2 17608.0 17639.7 17937.8 17993.6 18005.7 18224.9 18502.3 18518.0 14466.9 14835.4 15013.8 15075.5 15138.5 3559.5 3575.1 3576.8 3587.4 3780.8 146461.0 149159.3 150046.2 150603.0 152558.0 13582.2 13731.2 13766.8 13852.5 13885.5 26889.0 27076.3 27130.9 27342.3 27672.2 7984.4 8014.3 8059.3 8064.4 8067.2 4007.0 4036.7 4083.1 4094.1 4101.0 4412.4 4416.8 4480.8 4532.8 4533.7 857.3 887.2 896.2 897.1 897.2 544.9 639.1 647.3 670.6 681.2 8983.1 10907.0 11224.8 11259.2 11880.2 567.0 640.8 643.6 661.6 673.4 5238.4 5416.8 5744.7 5802.0 6428.8 118239.8 121939.4 122896.4 125234.0 142878.0 89808.6 113870.3 114352.0 114685.4 118663.6 97021.0 100501.5 101513.5 101989.0 103870.0 11313.4 12080.5 12405.0 12987.3 13182.5 44539.0 44891.3 45058.5 45383.5 45419.8 58025.7 60552.7 60810.8 61133.4 62312.4 13921.7 16977.4 17325.9 17392.6 17407.0 18760.7 18762.6 18975.3 19059.8 19175.5 17468.7 17583.6 17589.0 17699.5 18046.1 16863.1 17123.3 17127.4 17207.5 18493.0 17145.0 17342.7 18026.5 18343.4 18369.3 11655.8 11802.5 12647.9 12756.8 12936.4 11603.7 13182.7 14314.4 14836.3 14860.5 08:13:46 INFO - PID 1649 | -------- Summary: end -------- 08:13:46 INFO - PID 1649 | 08:13:46 INFO - TEST-INFO | 1649: exit 0 08:13:46 INFO - TEST-OK | dromaeo_css | took 1143067ms 08:13:46 INFO - TEST-START | kraken 08:13:46 INFO - operating with platform_type : mac_ 08:13:46 INFO - Initialising browser for kraken test... 08:13:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp_SJ19Z/profile 08:13:46 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 08:14:20 INFO - TEST-INFO | 1658: killed by SIGTERM 08:14:20 INFO - Browser initialized. 08:14:20 INFO - Running cycle 1/1 for kraken test... 08:14:20 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp_SJ19Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:14:22 INFO - PID 1662 | 2017-06-05 08:14:22.274 plugin-container[1663:10760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4737, name = 'com.apple.tsm.portname' 08:14:22 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:22 INFO - PID 1662 | 2017-06-05 08:14:22.276 plugin-container[1663:10760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 08:14:22 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:22 INFO - PID 1662 | 2017-06-05 08:14:22.276 plugin-container[1663:10760] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:14:23 INFO - PID 1662 | 2017-06-05 08:14:23.053 plugin-container[1664:10809] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 08:14:23 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:23 INFO - PID 1662 | 2017-06-05 08:14:23.053 plugin-container[1664:10809] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 08:14:23 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:23 INFO - PID 1662 | 2017-06-05 08:14:23.053 plugin-container[1664:10809] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:14:32 INFO - PID 1662 | RSS: Main: 261058560 08:14:32 INFO - PID 1662 | 08:14:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 08:14:36 INFO - PID 1662 | RSS: Main: 271048704 08:14:36 INFO - PID 1662 | 08:14:38 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 08:14:39 INFO - PID 1662 | RSS: Main: 280838144 08:14:39 INFO - PID 1662 | 08:14:42 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 08:14:43 INFO - PID 1662 | RSS: Main: 283926528 08:14:43 INFO - PID 1662 | 08:14:45 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 08:14:45 INFO - PID 1662 | RSS: Main: 284880896 08:14:45 INFO - PID 1662 | 08:14:47 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:14:48 INFO - PID 1662 | RSS: Main: 285515776 08:14:48 INFO - PID 1662 | 08:14:51 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 08:14:51 INFO - PID 1662 | RSS: Main: 291667968 08:14:51 INFO - PID 1662 | 08:14:54 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 08:14:55 INFO - PID 1662 | RSS: Main: 294699008 08:14:55 INFO - PID 1662 | 08:14:58 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 08:14:59 INFO - PID 1662 | RSS: Main: 293236736 08:14:59 INFO - PID 1662 | 08:15:00 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:15:01 INFO - PID 1662 | RSS: Main: 293212160 08:15:01 INFO - PID 1662 | 08:15:03 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:15:03 INFO - PID 1662 | RSS: Main: 293093376 08:15:03 INFO - PID 1662 | 08:15:06 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:15:06 INFO - PID 1662 | RSS: Main: 293064704 08:15:06 INFO - PID 1662 | 08:15:09 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:15:10 INFO - PID 1662 | RSS: Main: 293347328 08:15:10 INFO - PID 1662 | 08:15:13 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:15:14 INFO - PID 1662 | RSS: Main: 293277696 08:15:14 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 08:15:16 INFO - PID 1662 | RSS: Main: 294445056 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | __start_tp_report 08:15:16 INFO - PID 1662 | _x_x_mozilla_page_load 08:15:16 INFO - PID 1662 | _x_x_mozilla_page_load_details 08:15:16 INFO - PID 1662 | |i|pagename|runs| 08:15:16 INFO - PID 1662 | |0;ai-astar;107;116;115;112;114;116;123;116;114;114 08:15:16 INFO - PID 1662 | |1;audio-beat-detection;139;109;113;151;107;111;112;134;159;129 08:15:16 INFO - PID 1662 | |2;audio-dft;218;195;193;211;214;211;225;199;197;197 08:15:16 INFO - PID 1662 | |3;audio-fft;83;57;57;81;60;61;54;60;78;69 08:15:16 INFO - PID 1662 | |4;audio-oscillator;91;72;82;73;68;68;94;100;102;111 08:15:16 INFO - PID 1662 | |5;imaging-gaussian-blur;92;92;91;91;91;108;137;99;91;91 08:15:16 INFO - PID 1662 | |6;imaging-darkroom;125;98;97;98;98;98;127;96;98;130 08:15:16 INFO - PID 1662 | |7;imaging-desaturate;99;83;86;92;81;143;123;90;91;95 08:15:16 INFO - PID 1662 | |8;json-parse-financial;51;50;52;52;51;51;51;50;50;64 08:15:16 INFO - PID 1662 | |9;json-stringify-tinderbox;77;88;71;69;64;57;56;57;55;63 08:15:16 INFO - PID 1662 | |10;stanford-crypto-aes;85;87;102;111;110;85;93;87;111;92 08:15:16 INFO - PID 1662 | |11;stanford-crypto-ccm;135;138;136;138;138;139;142;149;149;140 08:15:16 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;165;237;222;189;198;239;211;231;193;195 08:15:16 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;66;67;64;68;67;65;73;78;92;74 08:15:16 INFO - PID 1662 | __end_tp_report 08:15:16 INFO - PID 1662 | __start_cc_report 08:15:16 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1196 08:15:16 INFO - PID 1662 | __end_cc_report 08:15:16 INFO - PID 1662 | __startTimestamp1496675716309__endTimestamp 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | ------- Summary: start ------- 08:15:16 INFO - PID 1662 | Number of tests: 14 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.50 stddev:3.97 (3.4%) stddev-sans-first:3.09 08:15:16 INFO - PID 1662 | Values: 107.0 116.0 115.0 112.0 114.0 116.0 123.0 116.0 114.0 114.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:131.50 stddev:18.84 (14.3%) stddev-sans-first:19.42 08:15:16 INFO - PID 1662 | Values: 139.0 109.0 113.0 151.0 107.0 111.0 112.0 134.0 159.0 129.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:206.00 Median:211.00 stddev:11.16 (5.3%) stddev-sans-first:10.95 08:15:16 INFO - PID 1662 | Values: 218.0 195.0 193.0 211.0 214.0 211.0 225.0 199.0 197.0 197.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:66.00 Median:65.00 stddev:10.90 (16.8%) stddev-sans-first:9.68 08:15:16 INFO - PID 1662 | Values: 83.0 57.0 57.0 81.0 60.0 61.0 54.0 60.0 78.0 69.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:72.50 stddev:15.62 (21.5%) stddev-sans-first:16.46 08:15:16 INFO - PID 1662 | Values: 91.0 72.0 82.0 73.0 68.0 68.0 94.0 100.0 102.0 111.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:91.00 stddev:14.67 (16.1%) stddev-sans-first:15.39 08:15:16 INFO - PID 1662 | Values: 92.0 92.0 91.0 91.0 91.0 108.0 137.0 99.0 91.0 91.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:98.00 stddev:14.44 (14.7%) stddev-sans-first:13.68 08:15:16 INFO - PID 1662 | Values: 125.0 98.0 97.0 98.0 98.0 98.0 127.0 96.0 98.0 130.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:90.50 stddev:19.62 (21.7%) stddev-sans-first:20.81 08:15:16 INFO - PID 1662 | Values: 99.0 83.0 86.0 92.0 81.0 143.0 123.0 90.0 91.0 95.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:51.00 stddev:4.21 (8.3%) stddev-sans-first:4.44 08:15:16 INFO - PID 1662 | Values: 51.0 50.0 52.0 52.0 51.0 51.0 51.0 50.0 50.0 64.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.50 stddev:10.72 (16.1%) stddev-sans-first:10.56 08:15:16 INFO - PID 1662 | Values: 77.0 88.0 71.0 69.0 64.0 57.0 56.0 57.0 55.0 63.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:96.30 Median:86.00 stddev:11.11 (12.9%) stddev-sans-first:11.00 08:15:16 INFO - PID 1662 | Values: 85.0 87.0 102.0 111.0 110.0 85.0 93.0 87.0 111.0 92.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:139.50 stddev:4.93 (3.5%) stddev-sans-first:4.82 08:15:16 INFO - PID 1662 | Values: 135.0 138.0 136.0 138.0 138.0 139.0 142.0 149.0 149.0 140.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.00 Median:216.50 stddev:24.13 (11.1%) stddev-sans-first:19.95 08:15:16 INFO - PID 1662 | Values: 165.0 237.0 222.0 189.0 198.0 239.0 211.0 231.0 193.0 195.0 08:15:16 INFO - PID 1662 | 08:15:16 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:70.50 stddev:8.51 (12.1%) stddev-sans-first:8.80 08:15:16 INFO - PID 1662 | Values: 66.0 67.0 64.0 68.0 67.0 65.0 73.0 78.0 92.0 74.0 08:15:16 INFO - PID 1662 | -------- Summary: end -------- 08:15:16 INFO - PID 1662 | 08:15:17 INFO - TEST-INFO | 1662: exit 0 08:15:17 INFO - TEST-OK | kraken | took 90546ms 08:15:17 INFO - SUITE-END | took 1233s 08:15:17 INFO - Completed test suite (00:20:33) 08:15:17 INFO - javascript benchmark 08:15:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8033.56315837255, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3044.1932887784733, 3048.7103725146103, 3603.189282411456, 3650.0874781304674, 3654.232611151658, 6133.426475724757, 6144.293406258282, 6241.438270555649, 6264.498840092793, 6284.057391391282, 6211.50307975362, 6217.5958562693395, 6234.625922444645, 6313.305536390993, 6328.145700330466, 4961.578265847389, 4995.35060442142, 5040.823571175009, 5434.7826086956575, 5480.479354461339, 5516.338039435253, 5610.0, 5614.719264036802, 5631.267935168423, 5659.179418984245, 5382.569394448435, 5442.210879422501, 5479.945200548023, 5485.451454854522, 5506.889862202774, 5509.338879334466, 5522.6134170608075, 5525.834224973257, 5539.9169012464845, 5555.0, 5448.645838020516, 5461.836144915659, 5505.146702261156, 5506.0, 5534.1975413564805, 4405.405270288502, 6167.321594624587, 6209.565330426872, 6233.906491402633, 6306.180196574416, 6700.396964273239, 6734.225564060147, 6746.865062698718, 6757.4457874688605, 6767.932320676779, 6718.126643536358, 6827.385535301847, 6829.965850170767, 6850.38346548813, 6857.211420686635, 6257.124002639634, 6262.1546091278005, 6295.3389894060465, 6370.203724534434, 6376.936230637727, 6902.206246281641, 6962.582245065312, 6980.685869135901, 6992.440604751608, 7006.544574602634, 6245.6252624842655, 6410.294867564564, 6434.0, 6588.110605068255, 6613.537052406382, 6728.562643428161, 6825.385715285549, 6847.897281540781, 6857.451403887677, 6865.519413640997, 6478.0, 6499.2850786413455, 6748.29142939994, 6749.8650026999185, 6762.425193858478, 6632.336766323426, 6649.634270115093, 6744.359285867933, 6771.356721111487, 6772.356626120511, 4149.8340066397, 5207.3230480037355, 5355.785768569292, 5390.838274851761, 5437.102878025082, 4012.959870401317, 4027.154297597479, 4730.787114579852, 4819.421669399694, 4873.780679869415, 6206.906896396557, 6314.936850631527, 6318.020706790455, 6347.174867267226, 6400.807471106433, 6358.872822543616, 6846.383825455633, 6849.075374824335, 6852.383285504328, 6858.828529286808, 6221.3156552779155, 6288.27684816252, 6337.302896681362, 6371.108044873721, 6387.137736405619, 4890.535399137077, 5743.483086522233, 5905.350411454737, 5971.104334349882, 5979.701014949235, 5828.94171058284, 6973.651317434108, 6994.68523916425, 7006.229314775269, 7022.192447868459, 6768.187817461936, 6825.249222585611, 6838.487113466609, 6847.1783385994, 6864.588124712333, 6738.797836064926, 6826.385625293548, 6988.9301106990315, 6992.965035174791, 7018.2630823762765, 6634.767783127568, 6661.966690166712, 6689.2307384651385, 6711.429528490133, 6728.259891412039, 6362.45919096882, 6840.521163518705, 6858.0, 6860.725570977105, 6867.828304292432, 5211.5049070338255, 6464.385883341075, 6558.409743123142, 6634.668266586555, 6641.0, 6593.076969224217, 6609.405153536205, 6624.271330153776, 6632.237292711207, 6653.467722582085, 5321.600879934098, 6291.1192433058495, 6296.307406185408, 6396.26442959047, 6479.8056058318325, 6583.374579414948, 6607.306232845482, 6613.603183808987, 6626.304238055128, 6648.5346025777735, 6387.393197646216, 6437.937740272802, 6441.0, 6445.935540644534, 6455.838604034937], "value": 6100.705638524576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.9880151797724, 813.5810057820223, 842.8904242448483, 1258.767128081305, 1326.8208791813101, 37844.0, 38385.04037399066, 38421.231575368474, 38452.807735961316, 38468.0, 32546.67453325466, 38385.808070959676, 38539.61460385395, 38628.80685596575, 38683.0, 7799.5710235937, 8223.383246256524, 8463.873041904377, 8474.23731864132, 8476.110008449117, 7178.3898368638465, 8276.0, 8365.372597055215, 8381.62282697277, 8399.732072340465, 8337.66649334029, 8404.369672274574, 8406.579671016425, 8408.411411201218, 8413.200745929127, 8322.62548185333, 8348.582570871493, 8374.916250837505, 8396.034456037572, 8409.537475438849, 8091.5145091294125, 8157.592120393957, 8161.8367632647605, 8166.754997350089, 8175.386845986574, 36021.21777213381, 36130.277394451965, 36147.0, 36362.0, 36679.26641467182, 3552.875649352286, 3572.142685755426, 3575.731820113476, 3575.8212089395424, 3576.678098971105, 29375.118746437427, 29732.55401168984, 29757.851210743807, 29796.0, 30039.0, 2641.960370594443, 3060.4491191585507, 3069.6469905960876, 3076.6769489203534, 3085.490894002487, 249166.0, 251786.0, 283526.99999999796, 303072.0, 304954.0, 46378.76810615925, 46568.30147547789, 46636.0, 46679.0, 46775.53224467712, 48102.03795924062, 48676.0, 48723.512764872605, 48729.756351218726, 49735.0, 14287.357068931922, 14369.497067602584, 14443.277836108153, 14491.420343186366, 14515.491957781427, 11501.309921404743, 11518.712032199095, 11601.187916845909, 11618.593349232906, 11634.360110193851, 11219.382933938712, 13909.374078166507, 13930.721385572231, 13931.373088210852, 13936.0, 5311.2033195020285, 6055.364186760415, 6193.848956492751, 6234.719437625317, 6258.123862659231, 998.4758002048983, 1002.5538635307219, 1004.382304882492, 1043.8590790243372, 1044.6970378590277, 23016.769832301463, 29184.124476265744, 29833.10500684983, 30265.24336891595, 30861.765529379023, 1065.4679736672904, 1066.3601838896755, 1112.8108221602347, 1113.832925061231, 1116.3525155409843, 11741.530338786353, 11933.0, 12180.198554332554, 12427.627171184879, 12428.316442595564, 296727.0, 298424.0, 299398.0, 300738.0, 302192.0, 52585.47414525882, 53274.668133296975, 53574.7321263399, 53662.0, 53666.73166634142, 1404.2838152542276, 1476.6382236351947, 1482.5033613739301, 1484.85893840085, 1495.4990078323665, 22624.434389140402, 23266.767332327137, 23608.881955590114, 23805.166819161248, 23881.0, 37564.62435375611, 37703.434448482745, 37760.62239377571, 37823.0, 38093.80953095217, 268077.0, 269030.0, 269046.0, 269696.65151674114, 270381.6480917583, 32394.676053239167, 34102.658973410944, 34230.82884585561, 34418.31163376669, 34427.4835877457, 43536.0, 43702.78148609364, 43720.0, 43724.56275437205, 43789.56210437855, 20212.696809547575, 26558.7344126564, 28099.157025289274, 29701.554476682435, 30274.0, 24474.63288050645, 28647.713522864506, 30054.699453006066, 30083.548746768378, 30544.389112217188, 36405.72579959689, 36463.635363646026, 36603.0, 36622.45066323954, 36646.816765916, 28968.0, 29612.0, 30140.397192056476, 30595.54106688357, 30739.0, 23503.764962350157, 23619.0, 23842.284731458083, 23851.284461466184, 23909.92405341729], "value": 17278.360966222735, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.5670860844649, 429.6562749800159, 436.98033588488505, 446.6627696089452, 448.5962633629733, 14658.926705366459, 15417.614559636015, 18834.90582547087, 22301.776982230193, 22506.66240006401, 19234.61530769384, 23220.0, 24139.517209655798, 24304.392390190253, 24599.508009839792, 6131.785387511449, 6301.842453938642, 6309.0, 6312.779052733155, 6331.588446750947, 6204.751809927598, 6253.874922501547, 6288.371162883721, 6300.905486417708, 6307.211598550181, 6019.428154325354, 6085.235281768282, 6091.57358984869, 6169.383061693818, 6250.656213908233, 6143.477804386633, 6184.103305020769, 6199.256089269294, 6199.783007594758, 6205.7517699291975, 5469.0, 5512.310961129859, 5519.089350257203, 5523.613347065707, 5524.723763811793, 26899.813043000024, 27717.029903953466, 28064.719352806416, 28161.436771264664, 28229.85885070582, 2211.9225827096134, 2238.1942500699734, 2247.5617254635354, 2257.9613377846213, 2263.0721404224355, 18126.818731812644, 18416.90791546034, 18647.720284195748, 18698.62602747951, 18711.251549938122, 2111.567128738605, 2133.4666333416644, 2175.7062796522423, 2183.1376606240606, 2198.5822693688147, 174870.12564937095, 175440.0, 177672.0, 203762.0, 205876.94123058728, 12684.0, 12865.678358040937, 13247.602571922858, 13250.138740981805, 13255.933720331337, 16715.247813848408, 16717.665646686997, 16724.331026759035, 16748.748768768477, 16766.664666706598, 7484.476086673881, 8953.0, 9073.047330030251, 9086.818263634823, 9152.0, 8678.6528538859, 8719.476831390137, 8723.781905452415, 8724.56377181151, 8837.955810220908, 7744.947588888949, 8204.917950820534, 8313.667453301921, 8344.916550834536, 8389.119142490074, 2249.5500899819776, 2254.132159118751, 2258.1419060756807, 2258.254775923941, 2446.718627357877, 948.2177203806887, 962.0009620009697, 963.7038183642989, 993.2600212841479, 1002.8646132772128, 19260.0, 19958.0, 20452.261557767757, 20459.59080818375, 21341.359759207247, 978.3836183204536, 1010.5806090472372, 1023.3501726403787, 1029.1509504658686, 1044.738815296176, 10399.376037437778, 10531.420771857622, 10536.367817930948, 10909.290896091728, 11278.436078196057, 552258.0, 552583.0, 561079.0, 564369.0, 571840.0, 120025.39987300182, 142518.0, 142787.0, 143535.2823235898, 143957.0, 1141.08713029576, 1154.8556430446195, 1173.3898372846072, 1175.5591653130077, 1191.1542804608653, 16774.832251677326, 18153.364632237808, 18201.362952296608, 18981.715274271173, 19273.325433609756, 64056.718865623356, 67122.32877671161, 67556.66221668861, 68158.0, 68609.0, 84131.57934210535, 84182.0, 84283.57858210916, 84838.72741908753, 87022.0, 8081.9191808083515, 8621.396502244783, 9593.664221752206, 9724.708258751965, 9885.357451765612, 13791.2414817182, 17073.82926170772, 18026.459206223833, 18301.084945752926, 18391.0, 14894.329755160827, 15028.0, 15046.022008569407, 15135.697286053997, 15181.874537564529, 12450.377481126088, 12905.483780648668, 13721.862781372058, 13773.517926872511, 13825.585232443043, 15163.848361516244, 15188.848111518744, 15373.69252614919, 15440.0, 15450.605156200358, 10140.391576505139, 10309.948450258, 10312.690619281433, 10314.896851031694, 10322.793544129225, 14612.926935365254, 14633.122012679274, 14677.045992010486, 14706.41174353014, 14796.48212312607], "value": 11030.995693991494, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1262.1921969939235, 1362.3937347880194, 1415.4621243927313, 1423.2314550142924, 1440.279860069965, 11685.883141168586, 11866.7626647467, 12152.696182595428, 12160.27038377698, 12560.937195314033, 10616.946915265433, 12118.151729378947, 12165.391730413467, 12301.876981230207, 12370.134090613661, 2043.2950632031925, 2136.3270569770552, 2227.0757635581213, 2268.6823844661762, 2276.931692049241, 2217.8225741940605, 2240.7423146338137, 2250.4261413339577, 2251.7748225177515, 2251.88740562972, 2212.7565967743535, 2217.090992692996, 2217.545403192342, 2226.4100013496436, 2237.049254067023, 2104.8000439958178, 2189.7591264960843, 2195.978040219601, 2203.60335139674, 2205.746339171, 1927.5952050069502, 2013.506690860734, 2182.7708090650412, 2188.67169924511, 2193.4187440328324, 9483.573239204252, 11938.403079845973, 12137.817932731015, 12139.210951288138, 12161.635150945485, 6394.273546142515, 6645.833854153637, 6647.700853461557, 6648.268690444047, 6680.164979377578, 3343.933121337584, 4167.708260421772, 4199.265128602513, 4276.893077673052, 4289.2922667759785, 954.2985905359556, 1196.4735516372768, 1201.1471854982972, 1223.2782658231597, 1223.363850797589, 16825.66348673032, 16931.830681693147, 17058.0, 17061.146942652817, 17066.82933170665, 5409.83770486886, 5444.646098003617, 5470.769076957732, 5537.916931246034, 5676.403977582377, 12498.50005999768, 12507.812382814265, 12523.937380313222, 12542.1847579907, 12542.184757990884, 6310.5267104967315, 6395.648239346788, 6433.0, 6437.549371544041, 6460.612363258219, 5967.313758917693, 5982.4017598239825, 6092.329843717188, 6105.603135796247, 6113.052476866093, 4467.24056910326, 4498.235299999008, 4563.634909207255, 4587.7017993830295, 4594.264917613166, 1293.8318018657515, 1309.1490531154825, 1316.8748968847945, 1323.8808507234396, 1328.860469650692, 676.7563677076249, 763.324502716543, 804.8712206047121, 828.5277391886567, 835.9331253499705, 6425.775097871552, 6702.899456508157, 6710.396064354231, 6826.385625293748, 6859.5541289816, 583.2388732703862, 610.1823556434399, 611.4496952742568, 612.5712001598898, 618.9257289125334, 4379.1241751649795, 4823.734694591761, 4928.088303663833, 5024.773885175176, 5260.789568417221, 15116.924415377853, 15326.770098448533, 15371.0, 15655.84344156544, 15674.764878526832, 14064.578062658136, 15369.615759605877, 15483.922580387027, 15657.060576365455, 15686.764698529303, 8625.39622226438, 8698.652053917773, 8801.427907186013, 8802.03177650458, 8815.559222038872, 5595.63628364155, 6985.860282794417, 7395.524103671266, 7515.962420187864, 7692.96145020415, 13515.729685406433, 13712.108712933627, 13794.93102534481, 13812.964027698165, 14039.0, 14342.107223190527, 14343.705007198798, 14522.273886305853, 14560.344784484936, 14651.633709157357, 9548.37935534188, 9784.070513301225, 9937.602495900082, 9951.701448956543, 9954.651587194414, 10951.45242737844, 10962.56149754001, 11024.559017639205, 11041.558337666725, 11070.391128487689, 8471.57642117904, 8718.389712720049, 8803.29573634095, 8966.282697384064, 8981.730548083307, 7575.393968482398, 8158.632861521127, 8380.784786206088, 8736.0, 8801.647934082564, 8382.874256886029, 8483.278921291512, 8505.872411913702, 8519.3184545235, 8535.274501667427, 6157.199564056644, 6167.5682702210415, 6549.541532092708, 6661.034150048119, 6738.191417029988, 5664.461876121762, 6312.684365781784, 6695.12963314766, 6753.3246675332075, 6761.932380676327], "value": 5304.513089413378, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.80809595202396, 401.29973196771635, 402.54914495764746, 409.57199726286035, 411.2617850957531, 5404.540614047805, 6422.9357706422925, 6553.934460655392, 6554.573952693072, 6621.404073633372, 5100.33695619569, 6383.712732927018, 6391.520635952299, 6405.167328247321, 6407.807765767023, 3359.5800524934384, 3766.9434958475645, 4076.714629975888, 4347.978260108695, 4665.976670116653, 3848.883823691126, 4676.508966558514, 4678.087772884289, 4689.859304220879, 4701.647376446763, 4423.623991960671, 4521.75651695783, 4662.300654901758, 4666.346711460398, 4672.229082201433, 4659.930101048487, 4674.976625116887, 4676.0, 4686.859394218179, 4689.906201875978, 3822.4075268333454, 4013.5986401359924, 4029.355303151511, 4033.3949907513816, 4039.838406463738, 5655.755733738571, 6156.815295541096, 6299.9370006299805, 6333.55665103443, 6342.302346741854, 5573.303337082864, 5583.190437386556, 5583.388694362181, 5589.127642239854, 5597.944020559783, 6854.931450685529, 6863.691133898987, 6870.488492531635, 6878.002689609979, 6881.13985751781, 4553.544645535453, 4560.0, 4560.977195114003, 4562.406887104688, 4575.8560359910025, 5586.217929489874, 5887.764489420418, 6838.350356716153, 7280.2719728027305, 7363.447741419415, 4623.468301145412, 4653.115068657454, 4660.161170989254, 4665.090307390026, 4670.719756814602, 4177.26897792885, 4720.740359280273, 4740.1230772307235, 4750.881227969328, 4752.691075080109, 5524.585905812246, 5529.419410961791, 5576.86057848557, 5693.231413759172, 5829.912551311733, 5692.345380281238, 6914.377706006484, 6956.130483689539, 6964.756233531802, 6982.476314276348, 1513.8486151384773, 1793.7309403589566, 1802.5583731985748, 1803.666321730484, 1807.530042189014, 1198.8801119887942, 1250.8936740376987, 1258.937053147339, 1268.9974919813428, 1269.853966793812, 5490.917636235459, 6035.0, 6507.362490259909, 6516.837079073061, 6542.901856472156, 7200.567965922061, 7206.891896621555, 7210.134783825974, 7233.132024157135, 7240.855182896313, 5421.430749771296, 6467.900456922334, 6474.4496717778575, 6474.611523308617, 6809.0, 3118.440779610195, 3642.7450078494253, 3649.1242101895355, 3742.8689995850013, 3761.6802571781705, 6344.651044192521, 6494.902576461357, 7332.936724174965, 7406.7037318506655, 7420.85158296831, 6014.398560143951, 6866.073080134218, 7402.666879990414, 7407.0, 7487.925120748723, 6744.954532047541, 6762.425193858577, 7335.376492998155, 7346.375558077516, 7403.888941665987, 5329.600279979095, 6133.659291041877, 6288.0, 6548.443382312556, 7194.892076618959, 5924.822255332347, 7099.254578269208, 7102.680379383043, 7121.643917804193, 7148.606826624586, 6762.459003279824, 7104.751333703295, 7229.855402891808, 7313.524620899624, 7405.555666660018, 4158.604932531404, 5129.743512824419, 5145.511176438233, 5162.380514338303, 5164.366173251594, 7165.820854478679, 7219.318147744583, 7238.565686058853, 7251.456140789356, 7251.963740181265, 6077.027675572064, 6940.826479338057, 6943.444524438134, 7007.334303241184, 7028.20780700918, 5236.39781425141, 6072.386929776776, 6948.6178260196175, 6960.37356637905, 7000.124984376953, 6766.1880574329425, 6811.250762416229, 6815.284395138439, 6833.829154270984, 6836.829079273058, 5062.493750624908, 6033.87932241344, 6166.335089525893, 6854.074699915447, 6977.476689248225, 5663.943360566342, 6440.42383046785, 6742.6628668567355, 6771.720400569506, 6789.490788190807], "value": 5000.925386950362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.2724620886698, 751.4476859508261, 834.5535138700797, 864.7449002544249, 865.195368307474, 17215.913920430412, 17636.118194090293, 17674.823251767477, 17721.291148354067, 17727.202275897584, 12782.041346898997, 12931.159474634142, 13041.869581304185, 14956.999999999973, 15986.76019859703, 963.595289978209, 976.1848856205041, 983.208517143699, 984.394597322645, 985.2265971212599, 981.3719219699398, 989.3420875118038, 991.3110388280148, 992.7121134870915, 993.3195760903768, 997.1175509673918, 1030.7886883188928, 1037.9792404151913, 1039.8024375368693, 1041.8489319048733, 1033.152814691953, 1035.6116456328878, 1035.7617747917946, 1038.2680210451651, 1038.3510306058713, 758.0865057605627, 767.48578452437, 775.1318523253935, 775.3215936055951, 778.0080397493185, 17555.29778808846, 17562.209700563504, 17608.0, 17639.73540396895, 17937.820621793744, 17993.55016124594, 18005.7299140513, 18224.906505609706, 18502.2599096036, 18518.0, 14466.85533144676, 14835.406583736638, 15013.774793378108, 15075.54773356801, 15138.545843624599, 3559.501669766235, 3575.106223444139, 3576.7853928764357, 3587.3722098632898, 3780.8298626561873, 146461.0, 149159.25420372936, 150046.24976875156, 150603.0, 152558.0, 13582.185068895898, 13731.24478153711, 13766.793498097537, 13852.515161969484, 13885.514007009908, 26889.0, 27076.32309192286, 27130.914763409637, 27342.316442089108, 27672.169834904984, 7984.40116991228, 8014.278714915686, 8059.274665280153, 8064.435489515678, 8067.1529489402765, 4006.979965100214, 4036.656884164879, 4083.0608959938786, 4094.0788322627172, 4100.979495102505, 4412.382266482695, 4416.823327066945, 4480.798364073625, 4532.773361331921, 4533.659975501851, 857.3484152044491, 887.2236792806294, 896.1665650944553, 897.0625696147541, 897.1701176411923, 544.8528897197734, 639.114825966031, 647.3364801078932, 670.597641415147, 681.1723755636925, 8983.101689830964, 10906.963838435337, 11224.831627525593, 11259.211855170222, 11880.208151084487, 566.9935863841631, 640.7789312687115, 643.5913195121038, 661.5732852310233, 673.4309508465338, 5238.449962753933, 5416.83749487516, 5744.684042377626, 5801.984652685763, 6428.8071357859335, 118239.81760182288, 121939.39030304791, 122896.38551807184, 125234.0, 142878.0, 89808.5509572461, 113870.29194562088, 114352.0, 114685.4265728666, 118663.62672746669, 97021.02978970262, 100501.49749251206, 101513.49243253736, 101989.0, 103870.0, 11313.434328283389, 12080.51677932873, 12405.0, 12987.337595792651, 13182.472701092232, 44539.0, 44891.326630101226, 45058.54941450675, 45383.54616453793, 45419.772901135286, 58025.70987145037, 60552.69723651354, 60810.78378432495, 61133.38866611455, 62312.37687623066, 13921.721565568834, 16977.405790797264, 17325.913370433067, 17392.565185870455, 17407.0, 18760.718589220898, 18762.624747505248, 18975.33586324472, 19059.809401905804, 19175.52061198481, 17468.650626987645, 17583.64832703313, 17589.0, 17699.469015929542, 18046.097695114928, 16863.072531010912, 17123.315067397663, 17127.400540981005, 17207.483775486755, 18493.0, 17144.971301721936, 17342.653146936736, 18026.549336266697, 18343.449696509128, 18369.265229390676, 11655.76688466243, 11802.527898884291, 12647.936760316139, 12756.808647870104, 12936.41786119608, 11603.6518904433, 13182.670433239246, 14314.355853986805, 14836.258187090818, 14860.479883204036], "value": 8714.172779551203, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 115.0, 112.0, 114.0, 116.0, 123.0, 116.0, 114.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 113.0, 151.0, 107.0, 111.0, 112.0, 134.0, 159.0, 129.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 195.0, 193.0, 211.0, 214.0, 211.0, 225.0, 199.0, 197.0, 197.0], "value": 206.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 57.0, 81.0, 60.0, 61.0, 54.0, 60.0, 78.0, 69.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 82.0, 73.0, 68.0, 68.0, 94.0, 100.0, 102.0, 111.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 91.0, 91.0, 91.0, 108.0, 137.0, 99.0, 91.0, 91.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 98.0, 97.0, 98.0, 98.0, 98.0, 127.0, 96.0, 98.0, 130.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 83.0, 86.0, 92.0, 81.0, 143.0, 123.0, 90.0, 91.0, 95.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 52.0, 52.0, 51.0, 51.0, 51.0, 50.0, 50.0, 64.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 88.0, 71.0, 69.0, 64.0, 57.0, 56.0, 57.0, 55.0, 63.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 102.0, 111.0, 110.0, 85.0, 93.0, 87.0, 111.0, 92.0], "value": 96.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 138.0, 136.0, 138.0, 138.0, 139.0, 142.0, 149.0, 149.0, 140.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 237.0, 222.0, 189.0, 198.0, 239.0, 211.0, 231.0, 193.0, 195.0], "value": 208.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 64.0, 68.0, 67.0, 65.0, 73.0, 78.0, 92.0, 74.0], "value": 71.4, "unit": "score"}]}]} 08:15:17 INFO - Return code: 0 08:15:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:15:17 INFO - # TBPL SUCCESS # 08:15:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:15:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:15:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:15:17 INFO - 2017-06-05 08:15:17.735 screenresolution[1670:11186] starting screenresolution argv=screenresolution get 08:15:17 INFO - 2017-06-05 08:15:17.737 screenresolution[1670:11186] Display 0: 1600x1200x32@60 08:15:17 INFO - 2017-06-05 08:15:17.743 screenresolution[1671:11189] starting screenresolution argv=screenresolution list 08:15:17 INFO - Available Modes on Display 0 08:15:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:15:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:15:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:15:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:15:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:15:17 INFO - Intel Iris: 08:15:17 INFO - Chipset Model: Intel Iris 08:15:17 INFO - Type: GPU 08:15:17 INFO - Bus: Built-In 08:15:17 INFO - VRAM (Dynamic, Max): 1536 MB 08:15:17 INFO - Vendor: Intel (0x8086) 08:15:17 INFO - Device ID: 0x0a2e 08:15:17 INFO - Revision ID: 0x0009 08:15:17 INFO - Displays: 08:15:17 INFO - Display: 08:15:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:15:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:15:17 INFO - Display Serial Number: 1600x1200 60 08:15:17 INFO - Main Display: Yes 08:15:17 INFO - Mirror: Off 08:15:17 INFO - Online: Yes 08:15:17 INFO - Rotation: Supported 08:15:17 INFO - Return code: 0 08:15:17 INFO - Running post-action listener: _resource_record_post_action 08:15:17 INFO - [mozharness: 2017-06-05 15:15:17.979727Z] Finished run-tests step (success) 08:15:17 INFO - Running post-run listener: _resource_record_post_run 08:15:18 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40269312; Write bytes: 569715712; Read time: 275; Write time: 1753 08:15:18 INFO - TinderboxPrint: CPU usage
53.3% 08:15:18 INFO - TinderboxPrint: I/O read bytes / time
40,269,312 / 275 08:15:18 INFO - TinderboxPrint: I/O write bytes / time
569,715,712 / 1,753 08:15:18 INFO - TinderboxPrint: CPU idle
2,345.9 (46.7%) 08:15:18 INFO - TinderboxPrint: CPU system
1,015.9 (20.2%) 08:15:18 INFO - TinderboxPrint: CPU user
1,663.9 (33.1%) 08:15:18 INFO - TinderboxPrint: Swap in / out
287,150,080 / 0 08:15:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247941632; Write bytes: 249693696; Read time: 18002; Write time: 546 08:15:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:15:18 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 36013568; Write bytes: 318649856; Read time: 247; Write time: 1203 08:15:18 INFO - Running post-run listener: _upload_blobber_files 08:15:18 INFO - Blob upload gear active. 08:15:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:15:18 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 08:15:18 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'] 08:15:18 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 08:15:18 INFO - (blobuploader) - INFO - Open directory for files ... 08:15:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:15:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:15:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:19 INFO - (blobuploader) - INFO - Done attempting. 08:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:20 INFO - (blobuploader) - INFO - Done attempting. 08:15:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:15:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:15:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:21 INFO - (blobuploader) - INFO - Done attempting. 08:15:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:15:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:15:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:23 INFO - (blobuploader) - INFO - Done attempting. 08:15:23 INFO - (blobuploader) - INFO - Iteration through files over. 08:15:23 INFO - Return code: 0 08:15:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:15:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:15:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a4cc79ce36b4aaa3ca3b5f29cc00ed9292688a02c2786b57e0ec7f9a3334e9bf202de069f75887501803d777e69115ef3c8311d46e626e702f8965d903ae846", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb57895cb05bab2f89c13dd906c2c8f162ea6366c8845b5ab5eb81981b8c8d8c40b875e153ce11750c8da07487a2a00a56bf1035cf59fd822e6c15181475e7ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7902128507859459ae7703dbfc6b8e8ea0d6202c156302f8256b71092d0173f60e0fbd7a3ab578139e7de00e358e23b43604fa749640af6c151e7baf09e6b90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/709fd7ded663aec356f62b42d35ad9586a32adb451ebb8c0f53bb3ded26fc32b9f1d2119a0219a7f4e88f3f10ce8c0a3e1e34f649408b0b2b472196e783b2649"} 08:15:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:15:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:15:23 INFO - Contents: 08:15:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a4cc79ce36b4aaa3ca3b5f29cc00ed9292688a02c2786b57e0ec7f9a3334e9bf202de069f75887501803d777e69115ef3c8311d46e626e702f8965d903ae846", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb57895cb05bab2f89c13dd906c2c8f162ea6366c8845b5ab5eb81981b8c8d8c40b875e153ce11750c8da07487a2a00a56bf1035cf59fd822e6c15181475e7ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7902128507859459ae7703dbfc6b8e8ea0d6202c156302f8256b71092d0173f60e0fbd7a3ab578139e7de00e358e23b43604fa749640af6c151e7baf09e6b90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/709fd7ded663aec356f62b42d35ad9586a32adb451ebb8c0f53bb3ded26fc32b9f1d2119a0219a7f4e88f3f10ce8c0a3e1e34f649408b0b2b472196e783b2649"} 08:15:23 INFO - Running post-run listener: copy_logs_to_upload_dir 08:15:23 INFO - Copying logs to upload dir... 08:15:23 INFO - mkdir: /builds/slave/test/build/upload/logs 08:15:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.981768 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-05 08:15:23.684652) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 08:15:23.688751) ========= 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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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/1a4cc79ce36b4aaa3ca3b5f29cc00ed9292688a02c2786b57e0ec7f9a3334e9bf202de069f75887501803d777e69115ef3c8311d46e626e702f8965d903ae846", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb57895cb05bab2f89c13dd906c2c8f162ea6366c8845b5ab5eb81981b8c8d8c40b875e153ce11750c8da07487a2a00a56bf1035cf59fd822e6c15181475e7ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7902128507859459ae7703dbfc6b8e8ea0d6202c156302f8256b71092d0173f60e0fbd7a3ab578139e7de00e358e23b43604fa749640af6c151e7baf09e6b90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/709fd7ded663aec356f62b42d35ad9586a32adb451ebb8c0f53bb3ded26fc32b9f1d2119a0219a7f4e88f3f10ce8c0a3e1e34f649408b0b2b472196e783b2649"} build_url:https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011075 build_url: 'https://queue.taskcluster.net/v1/task/FUTMD6MLRCS8yIu4_igkOQ/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/1a4cc79ce36b4aaa3ca3b5f29cc00ed9292688a02c2786b57e0ec7f9a3334e9bf202de069f75887501803d777e69115ef3c8311d46e626e702f8965d903ae846", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb57895cb05bab2f89c13dd906c2c8f162ea6366c8845b5ab5eb81981b8c8d8c40b875e153ce11750c8da07487a2a00a56bf1035cf59fd822e6c15181475e7ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7902128507859459ae7703dbfc6b8e8ea0d6202c156302f8256b71092d0173f60e0fbd7a3ab578139e7de00e358e23b43604fa749640af6c151e7baf09e6b90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/709fd7ded663aec356f62b42d35ad9586a32adb451ebb8c0f53bb3ded26fc32b9f1d2119a0219a7f4e88f3f10ce8c0a3e1e34f649408b0b2b472196e783b2649"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 08:15:23.725147) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:15:23.725725) ========= 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.29Xg4cK9ry/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.WvTFNxHdPS/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005052 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:15:23.775169) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 08:15:23.775509) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 08:15:23.775844) ========= ========= Total master_lag: 0.18 =========