builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0004 starttime: 1497090298.69 results: success (0) revision: a305f9e66fa5b6faec4804ace67bb69f3c5de2d3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:58.691421) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:58.691966) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:58.692339) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:58.719894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:58.720613) ========= 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.5rvmZmOz85/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.6IdeeYcGE3/Listeners TMPDIR=/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/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-10 03:24:58-- 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% 9.05M=0.001s 2017-06-10 03:24:59 (9.05 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.524960 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:59.272530) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:59.273304) ========= 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.5rvmZmOz85/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.6IdeeYcGE3/Listeners TMPDIR=/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/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.036171 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:24:59.334638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 03:24:59.335424) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a305f9e66fa5b6faec4804ace67bb69f3c5de2d3 --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 a305f9e66fa5b6faec4804ace67bb69f3c5de2d3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5rvmZmOz85/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.6IdeeYcGE3/Listeners TMPDIR=/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/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-10 03:24:59,437 truncating revision to first 12 chars 2017-06-10 03:24:59,438 Setting DEBUG logging. 2017-06-10 03:24:59,438 attempt 1/10 2017-06-10 03:24:59,438 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a305f9e66fa5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-10 03:25:00,196 unpacking tar archive at: mozilla-central-a305f9e66fa5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045162 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 03:25:00.406151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 03:25:00.406501) ========= script_repo_revision: a305f9e66fa5b6faec4804ace67bb69f3c5de2d3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 03:25:00.406909) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 03:25:00.407209) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 03:25:00.427627) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-10 03:25:00.428176) ========= /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.5rvmZmOz85/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.6IdeeYcGE3/Listeners TMPDIR=/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:25:00 INFO - MultiFileLogger online at 20170610 03:25:00 in /builds/slave/test 03:25:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:25:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:25:00 INFO - {'append_to_log': False, 03:25:00 INFO - 'base_work_dir': '/builds/slave/test', 03:25:00 INFO - 'blob_upload_branch': 'Firefox', 03:25:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:25:00 INFO - 'branch': 'Firefox', 03:25:00 INFO - 'buildbot_json_path': 'buildprops.json', 03:25:00 INFO - 'config_files': ('talos/mac_config.py',), 03:25:00 INFO - 'default_actions': ('clobber', 03:25:00 INFO - 'read-buildbot-config', 03:25:00 INFO - 'download-and-extract', 03:25:00 INFO - 'populate-webroot', 03:25:00 INFO - 'create-virtualenv', 03:25:00 INFO - 'install', 03:25:00 INFO - 'setup-mitmproxy', 03:25:00 INFO - 'run-tests'), 03:25:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:25:00 INFO - 'download_minidump_stackwalk': True, 03:25:00 INFO - 'download_symbols': 'ondemand', 03:25:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:25:00 INFO - 'tooltool.py': '/tools/tooltool.py', 03:25:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:25:00 INFO - '/tools/misc-python/virtualenv.py')}, 03:25:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:25:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:25:00 INFO - 'gecko_profile': False, 03:25:00 INFO - 'gecko_profile_interval': 0, 03:25:00 INFO - 'installer_path': 'installer.exe', 03:25:00 INFO - 'log_level': 'info', 03:25:00 INFO - 'log_name': 'talos', 03:25:00 INFO - 'log_to_console': True, 03:25:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:25:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:25:00 INFO - 'opt_config_files': (), 03:25:00 INFO - 'pip_index': False, 03:25:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:25:00 INFO - 'cmd': ('bash', 03:25:00 INFO - '-c', 03:25:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:25:00 INFO - 'enabled': True, 03:25:00 INFO - 'halt_on_failure': False, 03:25:00 INFO - 'name': 'check_screen_resolution'},), 03:25:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:25:00 INFO - 'cmd': ('bash', 03:25:00 INFO - '-c', 03:25:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:25:00 INFO - 'enabled': True, 03:25:00 INFO - 'halt_on_failure': False, 03:25:00 INFO - 'name': 'check_screen_resolution'},), 03:25:00 INFO - 'run_cmd_checks_enabled': True, 03:25:00 INFO - 'suite': 'dromaeojs-e10s', 03:25:00 INFO - 'system_bits': '32', 03:25:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:25:00 INFO - 'title': 't-yosemite-r7-0004', 03:25:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:25:00 INFO - 'use_talos_json': True, 03:25:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:25:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:25:00 INFO - 'work_dir': 'build'} 03:25:00 INFO - [mozharness: 2017-06-10 10:25:00.638507Z] Running clobber step. 03:25:00 INFO - Running pre-action listener: _resource_record_pre_action 03:25:00 INFO - Running main action method: clobber 03:25:00 INFO - rmtree: /builds/slave/test/build 03:25:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:25:03 INFO - Running post-action listener: _resource_record_post_action 03:25:03 INFO - [mozharness: 2017-06-10 10:25:03.265157Z] Finished clobber step (success) 03:25:03 INFO - [mozharness: 2017-06-10 10:25:03.265280Z] Running read-buildbot-config step. 03:25:03 INFO - Running pre-action listener: _resource_record_pre_action 03:25:03 INFO - Running main action method: read_buildbot_config 03:25:03 INFO - Using buildbot properties: 03:25:03 INFO - { 03:25:03 INFO - "project": "", 03:25:03 INFO - "product": "firefox", 03:25:03 INFO - "who": "archaeopteryx@coole-files.de", 03:25:03 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:25:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:25:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:25:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:25:03 INFO - "stage_platform": "macosx64", 03:25:03 INFO - "basedir": "/builds/slave/test", 03:25:03 INFO - "buildnumber": 20, 03:25:03 INFO - "platform": "yosemite_r7", 03:25:03 INFO - "parent_task_id": "OrGR1vOGT2ijWd8MnLUm2g", 03:25:03 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:25:03 INFO - "slavebuilddir": "test", 03:25:03 INFO - "taskId": "WrV4ymNPSzijJKBjXjZwCQ", 03:25:03 INFO - "branch": "mozilla-central", 03:25:03 INFO - "script_repo_revision": "production", 03:25:03 INFO - "revision": "a305f9e66fa5b6faec4804ace67bb69f3c5de2d3", 03:25:03 INFO - "slavename": "t-yosemite-r7-0004", 03:25:03 INFO - "repo_path": "mozilla-central" 03:25:03 INFO - } 03:25:03 INFO - Finding installer, test and symbols from parent task. 03:25:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WrV4ymNPSzijJKBjXjZwCQ'}, attempt #1 03:25:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WrV4ymNPSzijJKBjXjZwCQ'}, attempt #1 03:25:04 INFO - Task dependencies: bbyWgjMuR4-aBJXWLi7zOA OrGR1vOGT2ijWd8MnLUm2g 03:25:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g'}, attempt #1 03:25:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:25:04 INFO - Set test_url: https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/test_packages.json 03:25:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/target.crashreporter-symbols.zip 03:25:04 INFO - Running post-action listener: _resource_record_post_action 03:25:04 INFO - [mozharness: 2017-06-10 10:25:04.599708Z] Finished read-buildbot-config step (success) 03:25:04 INFO - [mozharness: 2017-06-10 10:25:04.599789Z] Running download-and-extract step. 03:25:04 INFO - Running pre-action listener: _resource_record_pre_action 03:25:04 INFO - Running main action method: download_and_extract 03:25:04 INFO - mkdir: /builds/slave/test/build/tests 03:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:04 INFO - trying https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:25:04 INFO - Downloading https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:25:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 03:25:05 INFO - Downloaded 1328 bytes. 03:25:05 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:25:05 INFO - Using the following test package requirements: 03:25:05 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:25:05 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:25:05 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:25:05 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:25:05 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'jsshell-mac.zip'], 03:25:05 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:25:05 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:25:05 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:25:05 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:25:05 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 03:25:05 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:25:05 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:25:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:25:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:25:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:25:05 INFO - Fetch https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:25:06 INFO - Content-Length response header: 59541518 03:25:06 INFO - Bytes received: 59541518 03:25:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:25:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:25:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:25:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:25:27 INFO - Content-Length response header: 59541518 03:25:27 INFO - Bytes received: 59541518 03:25:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:25:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:25:44 INFO - Fetch https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:25:45 INFO - Content-Length response header: 14449999 03:25:45 INFO - Bytes received: 14449999 03:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:46 INFO - trying https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:25:46 INFO - Downloading https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:25:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 03:25:48 INFO - Downloaded 69250015 bytes. 03:25:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:25:48 INFO - mkdir: /builds/slave/test/properties 03:25:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:25:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:25:48 INFO - Contents: 03:25:48 INFO - build_url:https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:25:48 INFO - Running post-action listener: _resource_record_post_action 03:25:48 INFO - Running post-action listener: set_extra_try_arguments 03:25:48 INFO - [mozharness: 2017-06-10 10:25:48.142688Z] Finished download-and-extract step (success) 03:25:48 INFO - [mozharness: 2017-06-10 10:25:48.142795Z] Running populate-webroot step. 03:25:48 INFO - Running pre-action listener: _resource_record_pre_action 03:25:48 INFO - Running main action method: populate_webroot 03:25:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:25:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:25:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:25:48 INFO - '--tppagecycles', 03:25:48 INFO - '7'], 03:25:48 INFO - 'tests': ['tsvgm']}}, 03:25:48 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:25:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:25:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:25:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:25:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:25:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['damp', 'tps']}, 03:25:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'tests': ['damp', 'tps']}, 03:25:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['dromaeo_dom']}, 03:25:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:25:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:25:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:25:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:25:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['a11yr', 03:25:48 INFO - 'ts_paint', 03:25:48 INFO - 'tpaint', 03:25:48 INFO - 'sessionrestore', 03:25:48 INFO - 'sessionrestore_no_auto_restore', 03:25:48 INFO - 'tabpaint']}, 03:25:48 INFO - 'other-e10s': {'tests': ['a11yr', 03:25:48 INFO - 'ts_paint', 03:25:48 INFO - 'tpaint', 03:25:48 INFO - 'sessionrestore', 03:25:48 INFO - 'sessionrestore_no_auto_restore', 03:25:48 INFO - 'tabpaint']}, 03:25:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:25:48 INFO - 'ts_paint', 03:25:48 INFO - 'tpaint', 03:25:48 INFO - 'sessionrestore', 03:25:48 INFO - 'sessionrestore_no_auto_restore', 03:25:48 INFO - 'tabpaint']}, 03:25:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:25:48 INFO - 'ts_paint', 03:25:48 INFO - 'tpaint', 03:25:48 INFO - 'sessionrestore', 03:25:48 INFO - 'sessionrestore_no_auto_restore', 03:25:48 INFO - 'tabpaint']}, 03:25:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['a11yr', 03:25:48 INFO - 'ts_paint', 03:25:48 INFO - 'tpaint', 03:25:48 INFO - 'sessionrestore', 03:25:48 INFO - 'sessionrestore_no_auto_restore', 03:25:48 INFO - 'tabpaint']}, 03:25:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['a11yr', 03:25:48 INFO - 'ts_paint', 03:25:48 INFO - 'tpaint', 03:25:48 INFO - 'sessionrestore', 03:25:48 INFO - 'sessionrestore_no_auto_restore', 03:25:48 INFO - 'tabpaint']}, 03:25:48 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:25:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:25:48 INFO - 'bloom_basic_ref']}, 03:25:48 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:25:48 INFO - 'talos_options': ['--mitmproxy', 03:25:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:25:48 INFO - '--firstNonBlankPaint'], 03:25:48 INFO - 'tests': ['quantum_pageload_google', 03:25:48 INFO - 'quantum_pageload_youtube', 03:25:48 INFO - 'quantum_pageload_amazon', 03:25:48 INFO - 'quantum_pageload_facebook']}, 03:25:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['tsvgx', 03:25:48 INFO - 'tsvgr_opacity', 03:25:48 INFO - 'tart', 03:25:48 INFO - 'tscrollx', 03:25:48 INFO - 'cart', 03:25:48 INFO - 'tsvg_static']}, 03:25:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:25:48 INFO - 'tsvgr_opacity', 03:25:48 INFO - 'tart', 03:25:48 INFO - 'tscrollx', 03:25:48 INFO - 'cart', 03:25:48 INFO - 'tsvg_static']}, 03:25:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'talos_options': ['--disable-e10s'], 03:25:48 INFO - 'tests': ['tp5o']}, 03:25:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:25:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'talos_options': ['--disable-e10s', 03:25:48 INFO - '--xperf_path', 03:25:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:25:48 INFO - 'tests': ['tp5n']}, 03:25:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:25:48 INFO - 'talos_options': ['--xperf_path', 03:25:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:25:48 INFO - 'tests': ['tp5n']}}, 03:25:48 INFO - 'talos.zip': {'path': '', 03:25:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:25:48 INFO - Running post-action listener: _resource_record_post_action 03:25:48 INFO - [mozharness: 2017-06-10 10:25:48.165896Z] Finished populate-webroot step (success) 03:25:48 INFO - [mozharness: 2017-06-10 10:25:48.166035Z] Running create-virtualenv step. 03:25:48 INFO - Running pre-action listener: _resource_record_pre_action 03:25:48 INFO - Running main action method: create_virtualenv 03:25:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:25:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:25:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:25:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:25:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:25:48 INFO - Using real prefix '/tools/python27' 03:25:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:25:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:25:51 INFO - Installing pip.................done. 03:25:51 INFO - Return code: 0 03:25:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:25:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:25:52 INFO - Reading from file tmpfile_stdout 03:25:52 INFO - Output received: 03:25:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:25:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:52 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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:25:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:25:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:25:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:25:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:52 INFO - 'HOME': '/Users/cltbld', 03:25:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:52 INFO - 'LOGNAME': 'cltbld', 03:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:52 INFO - 'MOZ_NO_REMOTE': '1', 03:25:52 INFO - 'NO_EM_RESTART': '1', 03:25:52 INFO - 'PAGER': '/bin/cat', 03:25:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:52 INFO - 'PWD': '/builds/slave/test', 03:25:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:52 INFO - 'SHELL': '/bin/bash', 03:25:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:25:52 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:25:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:52 INFO - 'USER': 'cltbld', 03:25:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:52 INFO - 'XPC_FLAGS': '0x0', 03:25:52 INFO - 'XPC_SERVICE_NAME': '0', 03:25:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:25:52 INFO - Cleaning up... 03:25:52 INFO - Return code: 0 03:25:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:52 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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:25:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:25:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:25:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:25:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:52 INFO - 'HOME': '/Users/cltbld', 03:25:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:52 INFO - 'LOGNAME': 'cltbld', 03:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:52 INFO - 'MOZ_NO_REMOTE': '1', 03:25:52 INFO - 'NO_EM_RESTART': '1', 03:25:52 INFO - 'PAGER': '/bin/cat', 03:25:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:52 INFO - 'PWD': '/builds/slave/test', 03:25:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:52 INFO - 'SHELL': '/bin/bash', 03:25:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:25:52 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:25:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:52 INFO - 'USER': 'cltbld', 03:25:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:52 INFO - 'XPC_FLAGS': '0x0', 03:25:52 INFO - 'XPC_SERVICE_NAME': '0', 03:25:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:52 INFO - Downloading/unpacking psutil>=3.1.1 03:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:54 INFO - Installing collected packages: psutil 03:25:54 INFO - Running setup.py install for psutil 03:25:55 INFO - building 'psutil._psutil_osx' extension 03:25:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:25:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:25:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:25:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:25:55 INFO - building 'psutil._psutil_posix' extension 03:25:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:25:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:25:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:25:55 INFO - ^ 03:25:55 INFO - 1 warning generated. 03:25:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:25:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:56 INFO - Successfully installed psutil 03:25:56 INFO - Cleaning up... 03:25:56 INFO - Return code: 0 03:25:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:56 INFO - 'HOME': '/Users/cltbld', 03:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:56 INFO - 'LOGNAME': 'cltbld', 03:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:56 INFO - 'MOZ_NO_REMOTE': '1', 03:25:56 INFO - 'NO_EM_RESTART': '1', 03:25:56 INFO - 'PAGER': '/bin/cat', 03:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:56 INFO - 'PWD': '/builds/slave/test', 03:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:56 INFO - 'SHELL': '/bin/bash', 03:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:25:56 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:56 INFO - 'USER': 'cltbld', 03:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:56 INFO - 'XPC_FLAGS': '0x0', 03:25:56 INFO - 'XPC_SERVICE_NAME': '0', 03:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:25:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:25:57 INFO - Installing collected packages: mozsystemmonitor 03:25:57 INFO - Running setup.py install for mozsystemmonitor 03:25:58 INFO - Successfully installed mozsystemmonitor 03:25:58 INFO - Cleaning up... 03:25:58 INFO - Return code: 0 03:25:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:58 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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:25:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:25:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:58 INFO - 'HOME': '/Users/cltbld', 03:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:58 INFO - 'LOGNAME': 'cltbld', 03:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:58 INFO - 'MOZ_NO_REMOTE': '1', 03:25:58 INFO - 'NO_EM_RESTART': '1', 03:25:58 INFO - 'PAGER': '/bin/cat', 03:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:58 INFO - 'PWD': '/builds/slave/test', 03:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:58 INFO - 'SHELL': '/bin/bash', 03:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:25:58 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:58 INFO - 'USER': 'cltbld', 03:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:58 INFO - 'XPC_FLAGS': '0x0', 03:25:58 INFO - 'XPC_SERVICE_NAME': '0', 03:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:58 INFO - Downloading/unpacking jsonschema==2.5.1 03:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:25:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:26:00 INFO - warning: no files found matching '*.txt' 03:26:00 INFO - no previously-included directories found matching 'build' 03:26:00 INFO - no previously-included directories found matching 'dist' 03:26:00 INFO - no previously-included directories found matching '.git*' 03:26:00 INFO - Installing collected packages: jsonschema, functools32 03:26:00 INFO - Running setup.py install for functools32 03:26:00 INFO - warning: no files found matching '*.txt' 03:26:00 INFO - no previously-included directories found matching 'build' 03:26:00 INFO - no previously-included directories found matching 'dist' 03:26:00 INFO - no previously-included directories found matching '.git*' 03:26:00 INFO - Successfully installed jsonschema functools32 03:26:00 INFO - Cleaning up... 03:26:00 INFO - Return code: 0 03:26:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26: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')]} 03:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:00 INFO - 'HOME': '/Users/cltbld', 03:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:00 INFO - 'LOGNAME': 'cltbld', 03:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:00 INFO - 'MOZ_NO_REMOTE': '1', 03:26:00 INFO - 'NO_EM_RESTART': '1', 03:26:00 INFO - 'PAGER': '/bin/cat', 03:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:00 INFO - 'PWD': '/builds/slave/test', 03:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:00 INFO - 'SHELL': '/bin/bash', 03:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:26:00 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:00 INFO - 'USER': 'cltbld', 03:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:00 INFO - 'XPC_FLAGS': '0x0', 03:26:00 INFO - 'XPC_SERVICE_NAME': '0', 03:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:26:00 INFO - Cleaning up... 03:26:00 INFO - Return code: 0 03:26:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26: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')]} 03:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:00 INFO - 'HOME': '/Users/cltbld', 03:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:00 INFO - 'LOGNAME': 'cltbld', 03:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:00 INFO - 'MOZ_NO_REMOTE': '1', 03:26:00 INFO - 'NO_EM_RESTART': '1', 03:26:00 INFO - 'PAGER': '/bin/cat', 03:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:00 INFO - 'PWD': '/builds/slave/test', 03:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:00 INFO - 'SHELL': '/bin/bash', 03:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:26:00 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:00 INFO - 'USER': 'cltbld', 03:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:00 INFO - 'XPC_FLAGS': '0x0', 03:26:00 INFO - 'XPC_SERVICE_NAME': '0', 03:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:02 INFO - Downloading blobuploader-1.2.4.tar.gz 03:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:26:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:26:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:03 INFO - Downloading docopt-0.6.1.tar.gz 03:26:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:26:03 INFO - Installing collected packages: blobuploader, requests, docopt 03:26:03 INFO - Running setup.py install for blobuploader 03:26:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:26:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:26:03 INFO - Running setup.py install for requests 03:26:03 INFO - Running setup.py install for docopt 03:26:04 INFO - Successfully installed blobuploader requests docopt 03:26:04 INFO - Cleaning up... 03:26:04 INFO - Return code: 0 03:26:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:04 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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:26:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:26:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:04 INFO - 'HOME': '/Users/cltbld', 03:26:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:04 INFO - 'LOGNAME': 'cltbld', 03:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:04 INFO - 'MOZ_NO_REMOTE': '1', 03:26:04 INFO - 'NO_EM_RESTART': '1', 03:26:04 INFO - 'PAGER': '/bin/cat', 03:26:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:04 INFO - 'PWD': '/builds/slave/test', 03:26:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:04 INFO - 'SHELL': '/bin/bash', 03:26:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:26:04 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:26:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:04 INFO - 'USER': 'cltbld', 03:26:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:04 INFO - 'XPC_FLAGS': '0x0', 03:26:04 INFO - 'XPC_SERVICE_NAME': '0', 03:26:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:04 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-wVA5e7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:04 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-0RRL7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:04 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-NajElj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:04 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-bOXJWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:04 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-Wf1PCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:04 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-2Xpsqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:04 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-GxdX7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-25I0lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-PgN7ZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-PC9kZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-MrE1Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-o5Ar_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-z0okqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-qiJ1pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-l9ewjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:05 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-_igCcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:06 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-kjvKhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:26:06 INFO - Running setup.py install for manifestparser 03:26:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:26:06 INFO - Running setup.py install for mozcrash 03:26:06 INFO - Running setup.py install for mozdebug 03:26:06 INFO - Running setup.py install for mozdevice 03:26:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:26:06 INFO - Running setup.py install for mozfile 03:26:06 INFO - Running setup.py install for mozhttpd 03:26:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:26:06 INFO - Running setup.py install for mozinfo 03:26:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:26:07 INFO - Running setup.py install for mozInstall 03:26:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:26:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:26:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:26:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:26:07 INFO - Running setup.py install for mozleak 03:26:07 INFO - Running setup.py install for mozlog 03:26:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:26:07 INFO - Running setup.py install for moznetwork 03:26:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:26:07 INFO - Running setup.py install for mozprocess 03:26:07 INFO - Running setup.py install for mozprofile 03:26:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:26:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:26:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:26:08 INFO - Running setup.py install for mozrunner 03:26:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:26:08 INFO - Running setup.py install for mozscreenshot 03:26:08 INFO - Running setup.py install for moztest 03:26:08 INFO - Running setup.py install for mozversion 03:26:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:26:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:26:08 INFO - Cleaning up... 03:26:08 INFO - Return code: 0 03:26:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:08 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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:08 INFO - 'HOME': '/Users/cltbld', 03:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:08 INFO - 'LOGNAME': 'cltbld', 03:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:08 INFO - 'MOZ_NO_REMOTE': '1', 03:26:08 INFO - 'NO_EM_RESTART': '1', 03:26:08 INFO - 'PAGER': '/bin/cat', 03:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:08 INFO - 'PWD': '/builds/slave/test', 03:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:08 INFO - 'SHELL': '/bin/bash', 03:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:26:08 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:08 INFO - 'USER': 'cltbld', 03:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:08 INFO - 'XPC_FLAGS': '0x0', 03:26:08 INFO - 'XPC_SERVICE_NAME': '0', 03:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:08 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-XcBPSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-GECoPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-TLQIPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-wEFRuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-v8IpgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-zGbvw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-E0Qsek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-79IndQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-RxUKnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:09 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-XaGjyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:10 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-fYc93x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:10 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-9liF_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:10 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-5l1idy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:10 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-jhuyF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:10 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-6YMTyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:10 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-OMRWT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:10 INFO - Running setup.py (path:/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/pip-fOzuhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:26:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:12 INFO - Downloading blessings-1.6.tar.gz 03:26:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:26:12 INFO - Installing collected packages: blessings 03:26:12 INFO - Running setup.py install for blessings 03:26:12 INFO - Successfully installed blessings 03:26:12 INFO - Cleaning up... 03:26:12 INFO - Return code: 0 03:26:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:26:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:12 INFO - Reading from file tmpfile_stdout 03:26:12 INFO - Current package versions: 03:26:12 INFO - blessings == 1.6 03:26:12 INFO - blobuploader == 1.2.4 03:26:12 INFO - docopt == 0.6.1 03:26:12 INFO - functools32 == 3.2.3-2 03:26:12 INFO - jsonschema == 2.5.1 03:26:12 INFO - manifestparser == 1.1 03:26:12 INFO - mozInstall == 1.13 03:26:12 INFO - mozcrash == 1.0 03:26:12 INFO - mozdebug == 0.1 03:26:12 INFO - mozdevice == 0.51 03:26:12 INFO - mozfile == 1.2 03:26:12 INFO - mozhttpd == 0.7 03:26:12 INFO - mozinfo == 0.10 03:26:12 INFO - mozleak == 0.1 03:26:12 INFO - mozlog == 3.4 03:26:12 INFO - moznetwork == 0.27 03:26:12 INFO - mozprocess == 0.25 03:26:12 INFO - mozprofile == 0.28 03:26:12 INFO - mozrunner == 6.13 03:26:12 INFO - mozscreenshot == 0.1 03:26:12 INFO - mozsystemmonitor == 0.3 03:26:12 INFO - moztest == 0.8 03:26:12 INFO - mozversion == 1.4 03:26:12 INFO - psutil == 3.1.1 03:26:12 INFO - requests == 1.2.3 03:26:12 INFO - wsgiref == 0.1.2 03:26:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:12 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 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:26:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:12 INFO - 'HOME': '/Users/cltbld', 03:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:12 INFO - 'LOGNAME': 'cltbld', 03:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:12 INFO - 'MOZ_NO_REMOTE': '1', 03:26:12 INFO - 'NO_EM_RESTART': '1', 03:26:12 INFO - 'PAGER': '/bin/cat', 03:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:12 INFO - 'PWD': '/builds/slave/test', 03:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:12 INFO - 'SHELL': '/bin/bash', 03:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:26:12 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:12 INFO - 'USER': 'cltbld', 03:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:12 INFO - 'XPC_FLAGS': '0x0', 03:26:12 INFO - 'XPC_SERVICE_NAME': '0', 03:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:26:13 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:14 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:26:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:26:14 INFO - Installing collected packages: marionette-driver 03:26:14 INFO - Running setup.py install for marionette-driver 03:26:14 INFO - Successfully installed marionette-driver 03:26:14 INFO - Cleaning up... 03:26:14 INFO - Return code: 0 03:26:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c374c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c378768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88734a33e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, '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.5rvmZmOz85/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.6IdeeYcGE3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:26:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:26:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:26:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:26:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:14 INFO - 'HOME': '/Users/cltbld', 03:26:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:14 INFO - 'LOGNAME': 'cltbld', 03:26:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:14 INFO - 'MOZ_NO_REMOTE': '1', 03:26:14 INFO - 'NO_EM_RESTART': '1', 03:26:14 INFO - 'PAGER': '/bin/cat', 03:26:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:14 INFO - 'PWD': '/builds/slave/test', 03:26:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:14 INFO - 'SHELL': '/bin/bash', 03:26:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:26:14 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:26:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:14 INFO - 'USER': 'cltbld', 03:26:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:14 INFO - 'XPC_FLAGS': '0x0', 03:26:14 INFO - 'XPC_SERVICE_NAME': '0', 03:26:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:26:15 INFO - Cleaning up... 03:26:15 INFO - Return code: 0 03:26:15 INFO - Running post-action listener: _resource_record_post_action 03:26:15 INFO - Running post-action listener: _start_resource_monitoring 03:26:15 INFO - Starting resource monitoring. 03:26:15 INFO - [mozharness: 2017-06-10 10:26:15.205008Z] Finished create-virtualenv step (success) 03:26:15 INFO - [mozharness: 2017-06-10 10:26:15.206039Z] Running install step. 03:26:15 INFO - Running pre-action listener: _resource_record_pre_action 03:26:15 INFO - Running main action method: install 03:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:15 INFO - Reading from file tmpfile_stdout 03:26:15 INFO - Detecting whether we're running mozinstall >=1.0... 03:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:26:15 INFO - Reading from file tmpfile_stdout 03:26:15 INFO - Output received: 03:26:15 INFO - Usage: mozinstall [options] installer 03:26:15 INFO - Options: 03:26:15 INFO - -h, --help show this help message and exit 03:26:15 INFO - -d DEST, --destination=DEST 03:26:15 INFO - Directory to install application into. [default: 03:26:15 INFO - "/builds/slave/test"] 03:26:15 INFO - --app=APP Application being installed. [default: firefox] 03:26:15 INFO - mkdir: /builds/slave/test/build/application 03:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:26:37 INFO - Reading from file tmpfile_stdout 03:26:37 INFO - Output received: 03:26:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:26:37 INFO - Running post-action listener: _resource_record_post_action 03:26:37 INFO - [mozharness: 2017-06-10 10:26:37.356382Z] Finished install step (success) 03:26:37 INFO - [mozharness: 2017-06-10 10:26:37.356524Z] Running setup-mitmproxy step. 03:26:37 INFO - Running pre-action listener: _resource_record_pre_action 03:26:37 INFO - Running main action method: setup_mitmproxy 03:26:37 INFO - Skipping: mitmproxy is not required 03:26:37 INFO - Running post-action listener: _resource_record_post_action 03:26:37 INFO - [mozharness: 2017-06-10 10:26:37.357127Z] Finished setup-mitmproxy step (success) 03:26:37 INFO - [mozharness: 2017-06-10 10:26:37.357240Z] Running run-tests step. 03:26:37 INFO - Running pre-action listener: _resource_record_pre_action 03:26:37 INFO - Running main action method: run_tests 03:26:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:26:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:26:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:26:37 INFO - 2017-06-10 03:26:37.372 screenresolution[1635:8163] starting screenresolution argv=screenresolution get 03:26:37 INFO - 2017-06-10 03:26:37.375 screenresolution[1635:8163] Display 0: 1600x1200x32@60 03:26:37 INFO - 2017-06-10 03:26:37.382 screenresolution[1636:8165] starting screenresolution argv=screenresolution list 03:26:37 INFO - Available Modes on Display 0 03:26:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:26:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:26:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:26:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:26:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:26:37 INFO - Intel Iris: 03:26:37 INFO - Chipset Model: Intel Iris 03:26:37 INFO - Type: GPU 03:26:37 INFO - Bus: Built-In 03:26:37 INFO - VRAM (Dynamic, Max): 1536 MB 03:26:37 INFO - Vendor: Intel (0x8086) 03:26:37 INFO - Device ID: 0x0a2e 03:26:37 INFO - Revision ID: 0x0009 03:26:37 INFO - Displays: 03:26:37 INFO - Display: 03:26:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:26:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:26:37 INFO - Display Serial Number: 1600x1200 60 03:26:37 INFO - Main Display: Yes 03:26:37 INFO - Mirror: Off 03:26:37 INFO - Online: Yes 03:26:37 INFO - Rotation: Supported 03:26:37 INFO - Return code: 0 03:26:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:26:37 INFO - Python 2.7.3 03:26:37 INFO - Return code: 0 03:26:37 INFO - grabbing minidump binary from tooltool 03:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:37 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 0x10c0cad30>, '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 0x10c094cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:26:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:26:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:26:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:26:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:26:37 INFO - Return code: 0 03:26:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:26:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:26:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:26:37 INFO - ENV: RUST_BACKTRACE is now 1 03:26:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:26:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:26:37 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/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:26:37 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/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5rvmZmOz85/Render', 03:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:37 INFO - 'HOME': '/Users/cltbld', 03:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:37 INFO - 'LOGNAME': 'cltbld', 03:26:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:26:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:37 INFO - 'MOZ_NO_REMOTE': '1', 03:26:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:26:37 INFO - 'NO_EM_RESTART': '1', 03:26:37 INFO - 'PAGER': '/bin/cat', 03:26:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:37 INFO - 'PWD': '/builds/slave/test', 03:26:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:37 INFO - 'RUST_BACKTRACE': '1', 03:26:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:26:37 INFO - 'SHELL': '/bin/bash', 03:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6IdeeYcGE3/Listeners', 03:26:37 INFO - 'TMPDIR': '/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/', 03:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:37 INFO - 'USER': 'cltbld', 03:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:37 INFO - 'XPC_FLAGS': '0x0', 03:26:37 INFO - 'XPC_SERVICE_NAME': '0', 03:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:37 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/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:26:38 INFO - mozversion application_buildid: 20170610021823 03:26:38 INFO - mozversion application_changeset: a305f9e66fa5b6faec4804ace67bb69f3c5de2d3 03:26:38 INFO - mozversion application_display_name: Nightly 03:26:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:26:38 INFO - mozversion application_name: Firefox 03:26:38 INFO - mozversion application_remotingname: firefox 03:26:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:26:38 INFO - mozversion application_vendor: Mozilla 03:26:38 INFO - mozversion application_version: 55.0a1 03:26:38 INFO - mozversion platform_buildid: 20170610021823 03:26:38 INFO - mozversion platform_changeset: a305f9e66fa5b6faec4804ace67bb69f3c5de2d3 03:26:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:26:38 INFO - mozversion platform_version: 55.0a1 03:26:38 INFO - using testdate: 1497090398 03:26:38 INFO - actual date: 1497090398 03:26:38 INFO - starting webserver on 'localhost:49229' 03:26:38 INFO - SUITE-START | Running 2 tests 03:26:38 INFO - TEST-START | dromaeo_css 03:26:38 INFO - operating with platform_type : mac_ 03:26:38 INFO - Initialising browser for dromaeo_css test... 03:26:38 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/tmpf7qlDn/profile 03:26:38 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 03:27:11 INFO - TEST-INFO | 1646: killed by SIGTERM 03:27:11 INFO - Browser initialized. 03:27:11 INFO - Running cycle 1/1 for dromaeo_css test... 03:27:11 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/tmpf7qlDn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:27:13 INFO - PID 1654 | 2017-06-10 03:27:13.725 plugin-container[1655:8663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 03:27:13 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:27:13 INFO - PID 1654 | 2017-06-10 03:27:13.725 plugin-container[1655:8663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 03:27:13 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:27:13 INFO - PID 1654 | 2017-06-10 03:27:13.725 plugin-container[1655:8663] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:27:14 INFO - PID 1654 | 2017-06-10 03:27:14.480 plugin-container[1656:8710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 03:27:14 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:27:14 INFO - PID 1654 | 2017-06-10 03:27:14.481 plugin-container[1656:8710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 03:27:14 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:27:14 INFO - PID 1654 | 2017-06-10 03:27:14.481 plugin-container[1656:8710] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:27:23 INFO - PID 1654 | RSS: Main: 252919808 03:27:23 INFO - PID 1654 | 03:30:25 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 03:30:27 INFO - PID 1654 | RSS: Main: 263340032 03:30:27 INFO - PID 1654 | 03:33:29 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 03:33:29 INFO - PID 1654 | RSS: Main: 274784256 03:33:29 INFO - PID 1654 | 03:36:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 03:36:32 INFO - PID 1654 | RSS: Main: 276733952 03:36:32 INFO - PID 1654 | 03:39:33 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 03:39:34 INFO - PID 1654 | RSS: Main: 280387584 03:39:34 INFO - PID 1654 | 03:42:37 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 03:42:38 INFO - PID 1654 | RSS: Main: 279347200 03:42:38 INFO - PID 1654 | 03:45:39 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 03:45:40 INFO - PID 1654 | RSS: Main: 280293376 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | __start_tp_report 03:45:40 INFO - PID 1654 | _x_x_mozilla_page_load 03:45:40 INFO - PID 1654 | _x_x_mozilla_page_load_details 03:45:40 INFO - PID 1654 | |i|pagename|runs| 03:45:40 INFO - PID 1654 | |0;dojo.html;2986.3430045390014;3629.6370362963708;3636.2727454509104;3656.1956369598697;3663.230721548475;4769.952300476994;4881.804727810883;5724.141378793178;6142.109394137848;6300.5274604404685;6198.876022479559;6262.12330273762;6274.937250627492;6281.968590157043;6314.463270622003;5291.391489978663;5436.782528698847;5505.449455054492;5521.171824226358;5596.3284405871145;5597.188407680884;5601.691906945116;5607.803726869561;5608.214849920994;5630.7184640768;5400.405955344909;5453.209284653703;5498.752556134984;5512.33851937768;5528.889422211573;5511.807086751945;5517.503424691797;5545.972270138624;5548.389677135512;5581.107022876361;5466;5475.589330800166;5494.203340515603;5496.422875598085;5510.559155267569;6199.87600247997;6233.937660623381;6283.748650053993;6286.559940804145;6324.3991820776955;6789.694463749143;6813.693383797741;6814.522983391164;6837.965810170967;6847.897281540781;6876.415504682058;6889.621070841102;6897.206821215575;6916.688749006306;7025.051618031552;6296;6296.275928268216;6371.076193951943;6373.904391434132;6390.265119511269;7007.579545227302;7032.191298000693;7040.5071644984355;7054.365107140279;7060.470464715167;6432.485401167895;6514.57655252407;6580.309067547934;6680.565763225375;6687.297833727389;6881.793546193622;6885.51801373899;6891.552049116892;6897.517173797886;6915.757948471779;6486.383793539606;6566.212054553484;6646.767363142267;6758.05387245786;6776.220734615582;4871.756412179377;6717.899231511531;6775.220849602359;6780.355866192704;6816.795496135124;5385.380681221632;5421.783128674809;5426.75579598919;5432.864178395493;5475.123980163155;4596.49438561758;4722.716637001791;4787.736674482867;4817.2774083887;4845.103655823684;6240.500759939194;6305.3379395163765;6356.396142366468;6363.109164716943;6368.10846481493;6896.7586134485055;6900.413464855544;6914.308569143146;6917.031615573824;6935.271796461299;6329.145565348711;6331.176946996861;6365.3316401778075;6384.776532821329;6389.9680501597195;5869.119632055226;5980.372060933626;6070.180525629072;6073.544484163617;6117.279373342116;7020.824479388056;7035.1909530404655;7037.964810175917;7044.119485064367;7052.294770523009;6881.827954300982;6888.552244104118;6897.655117244218;6939.444844412334;6948.583085014713;5983.401659833982;6176.4441200292185;6553.672316384066;6567.802965911031;6681.799546013627;6065.4541091302;6734.6632668367365;6773.559718618274;6781.220159681699;6794.422474089758;5579.69311687861;6656.201255849329;6882.346177113166;6892.827679308058;6900.447964162955;6536.934630653633;6604.669766511752;6638;6644.83387915306;6729.528932974645;6656.534042617164;6667.766628167991;6680.3987641112535;6680.665966701743;6682.3317668232785;6345.46063584582;6446.8388290293115;6519.967400162969;6525.64108974011;6527.216733991761;6490.188726409199;6581.3747693969;6639.103720997797;6644.468442524683;6688.933110668831;6470.158879345655;6471.838204044937;6515.250746164252;6523.086767852409;6539.280679125197 03:45:40 INFO - PID 1654 | |1;ext.html;666.8532922756995;691.7962744824006;832.1636755061164;1284.608194500677;1311.2001678975817;35480.64519354806;36524;36569;36598.26803463929;36607.999999999935;37112.81443592785;37376.43935340965;37390.43914341287;37451.438228426596;37715.811420942926;7638.656260468279;7734.922650773477;7748.147703752611;7755.612219389036;7759.41804364672;7717.884231736529;7795.688172473095;7806.375489960789;7810.258025487598;7826.72606458774;7771.689132434724;7994.080680721704;8216.424850260484;8221.342292616606;8221.424500284982;8173.6730530779;8176.713815016446;8219.136990615989;8223.21879421457;8230.135835737221;7953.12515623281;8021.67913283468;8047.597620119029;8053.959730201312;8066.4353495255355;34483.96548103561;34864.82567587171;34913.6508634913;34924.82537587321;35027.4745878812;3578.7673801202836;3581.301646178996;3622.275544891006;3623.9094022649383;3625.202455459821;29026.564601530994;29109.56335654967;29132.83468661251;29171.41657166866;29174.41651166965;2573.2923446052487;3051.313454472748;3061.11227743954;3062.693730626942;3080.091373044957;211283;211638;262442;268152;268708;45601.08797824025;45637.08725825465;45819.31271030937;45838;45856;40050.59949400527;45108.09783804305;47365.76317118392;47877;48000.27999580009;13621.659458513419;13686.589402317946;13808.516701915385;13817;13819.654508637364;10006.299559030944;11299;11301.49143288554;11308.208425410307;11327.150463715254;11918.404079795975;12785.552505662257;12914.483420663257;12916.677083072811;12954.093213474966;5264.6051546134195;5862.062070068769;6074.362191969868;6108.358622344679;6118.265808103056;910.535626830308;982.5529384130203;1004.3070281505737;1008.4050410257983;1051.295631926611;20609.896950515453;20802.375928722162;22109.33792862329;22378.32865014052;22649.660255096187;1090.6182836007333;1098.662669138815;1144.9427528623703;1149.7815415071277;1157.432857899623;9952.651657191964;10961.83450288885;11785.528578856922;12161.939190303992;12282.38588070593;278588;323490;324349;324423;324550;51266.23100653493;51487.48512514902;51574;51658.48341516612;51710.74144629328;1370.3216907630785;1427.2078996157231;1500.7973923520403;1527.618095476131;1534.2942246566704;18032.729509057113;21620.45443392109;22257.77742222622;22460.550788984456;23589.764102358757;36624;36685;36690.08274793151;36710.08224794402;36719;267211;267797;269422;269424;270008;31623.84188079045;32768.344633107685;32815.34369312553;32996.67003329936;33123.50314745329;34241.31517369689;41905;42966.78516607522;42994.78502607467;43157.352639711055;23465.76534234704;28535.42929141447;28622.284442889093;29053.854730726212;30119.3976120472;28373.85813071004;29756.553651695674;29857.55213671753;30027.249318767204;30561.388772224876;34250.82874585611;35263.64736352603;35432.82283588566;35550.288994220486;36028.639713602526;29367.55948660729;29643.85178074096;30726.385472290876;30838.845805770827;31207;22604;23517.29448116559;23691.933862975864;23946.76053239445;24098.15656452016 03:45:40 INFO - PID 1654 | |2;jquery.html;432.9350597410388;478.7630123089067;480.16691041043805;487.359122753579;499.91501444754385;21499.140034398646;21791.237306694267;22754.544909101827;22815.885920570396;24616.261512154662;24333.63499547508;24956.376090597743;24971.37571560712;25012.49975000499;25031.374215644617;6296.118543403927;6421.582597131195;6432.742690292383;6441.903371449432;6460.321666225049;6111.236095488064;6396.2324521057535;6409.807705768834;6461.06314584385;6474.514411419139;6344.555881088326;6378.553501254914;6419.390157934989;6426.164598602199;6443.967780161116;6412.903806442884;6435.806925792187;6436.967815160918;6443.130177426081;6454.160959075337;5844.415558444164;5845.152452894306;5879.441453061952;5895.675737834417;5896.469317761422;22215.22246721373;24537;30232.69767302321;30460.8476957616;30531.847340763376;1938.5056810513229;2249.381420109483;2260.5930932432157;2269.0810221860174;2271.863688178698;18721.344752933717;18952;19047.571432142508;19051.428457146307;19077.80922190774;1946.8539859510452;2065.4423305707523;2200.8899555022185;2237.172246268891;2246.898889549958;173028;180212;202674.9732502671;204990;211300.99999999846;14622.812821826901;15156;15214.847851521343;15452.38190472391;15570.532884013497;20393.49016274605;20463.795362046487;20482.692759608617;20513;20524.281650142173;8291;9883.06110919476;9912.702618921443;9917.950410248048;9920.702378928643;7397;9089.318301127441;9091.27269818413;9144.314176436794;9172.724818255463;9176.036516165706;9187.862182067274;9235.953820230856;9250.768730781785;9303.25573954082;2197.8791166485676;2234.832387570939;2617.437250991046;2631.7105118436957;2745.958810617842;887.7203680840514;968.5738275159049;970.369259981018;1010.3836659637615;1022.2486472442748;17580.648387032186;21201;22158.88920555387;22302.330930072123;22453.887730561244;987.688878003441;1004.7036124343336;1026.3995562595965;1038.6883934819523;1044.6187141693226;10722.571097156182;10818.56725730962;10836.688064410893;11334.60328888485;11606.30362178272;511621;546125.2693736505;546257;552214;558872;150402.24798875937;151527;151752;160150;163524;1163.8943004146095;1178.5403692559908;1188.851393575803;1194.098455665984;1195.2888031621171;18061.729074064162;18290.35983740616;18523.981181034633;18931.242750290385;19461.90269048646;68715.65642171758;69985.30014699788;71280;71538.64230679022;71872.04703741135;94283.05716943018;94306;95488;95514;95661.56507652251;11221.495032723384;11302.773944521228;11382.317060976367;11517.25137866036;11535.653930382103;18066;18217.089145542934;18459.892406455656;18509.53726156857;18550.25798968026;14697.19165445868;14938.701225975203;15005.774913376526;15025.248737563297;15088.54734357971;13256.93371533136;13258.86741132615;13273.734525309246;13643.454261829416;13833.930830345784;14791.556253312418;14912.850871491146;15034.849651503782;15228.695426091637;15579.844201557838;10088.848667269851;11168.21822472419;11395;11426.94286528562;11468.483918223867;14695.779563306773;14871;14939.850601494281;15019.249037547861;15054.849451505344 03:45:40 INFO - PID 1654 | |3;mootools.html;1296.429570988765;1424.8575142485752;1425.6435891027243;1450.673598440351;1463.4999125895959;12869.227846329226;13294.601161965133;13306.667333316673;13436.52972145974;13755.862441375582;13215.537456189035;13416.798748018788;13423.731525369487;13428.66428339292;13438.260895650736;2695.9865200674017;2735.179446166152;2738.4933787249324;2753.4768394005173;2765.764909982654;2683.329167708073;2729.945401091977;2740.0957683964343;2743.5198840202984;2747.4505098980185;2289;2689.7041325454184;2719.02115238514;2719.3745438549145;2730.604062410949;2647.205838248537;2678.3036410533305;2685.8791354388904;2689.3007817967273;2690.5695088785897;2577.755113264237;2646.0606484697846;2663.0146845667227;2684.0203325785947;2766.543520319165;13110.213387196703;13226.272555009471;13226.933865330611;13251.536196233086;13258.53595124166;7325.523840950321;7592;7607.353374963135;7622.199669034727;7647.3117419431965;4559.74921379324;4693.929591056137;4740.857774266778;4746.311784791221;4830.73430961297;1192.3263356203804;1437.5902867682764;1455.8034665320151;1462.4369617697157;1464.4288727396324;19747.90126049375;19820.80179198204;19940.70088948667;19947.601047979104;19979.60040799176;6350.682465876687;6431.646259455775;6548.70530826114;6647.833804154838;6655.5341126121675;14223.786643200361;14223.857761422254;14225.644358890904;14226.288685565887;14239.359228834728;7260.41916646667;7316.926830731731;7331.450141239322;7406.962965185139;7423.9257607424315;6624.867502650017;6763.492738044666;6815.863682726318;6820.727170913108;6905.413039891565;4182.853600123981;4309.978450107792;4766.594839438687;5002.799888004512;5034.345535080416;1466.5087195789317;1496.8428315026983;1500.4298366620615;1512.7125846089207;1515.8181018277878;741.5476559298824;806.5040000399712;832.904216015166;850.2093053460342;858.3176374782063;7543.8114047148165;7801;7811.960940195377;7949.6025198739835;8171.142030086874;809.7935026568305;847.3306088190261;970.7184916374314;1012.5038731021747;1015.7206768138673;4581.083783243364;4883.609311255091;5094.465081166498;5131.589472842238;5266.8946621068135;17506.299748009937;17747.645047098984;17896;18151.183196756177;18336.991465469262;17511.299548017938;17835.643287134444;18137;18258.817411825978;18290.908545457187;7976.641051152712;9195.816083678288;9297.767555811159;9368.016358282419;9426.811463770686;6770.593764374154;7063.646817659199;7484.887726683995;7652.426068045031;7957.363410926996;14720.484783032542;15895.284712188211;16116.355345786038;16247.837521625106;16277.67444651124;15710.293036813142;16208.594785130466;16437.260323285722;16690.4158354457;16693.415730449375;9574.234061275221;9606.799150106237;9733.756656083655;9774.951125244328;9856.162226210852;12026.518939242682;12109.33398663082;12135.150539462153;12253.754924901274;12288.38558072075;9804.097771387593;10042.648507302209;10178.931212222906;10194;10212.285140040125;9848.901510984799;9859.556319965475;9874.012598740355;9905.900940990497;10035.54840032215;8904.376693631384;9204.539773011456;9365.765855853659;9367.250619950251;9384.436933783994;5695;6560.3111673273615;6597.439217666552;6625.436837868835;7035.05026821393;6584.670766461562;6601.504887133388;6844.4182244509775;6955.686994085379;6993.405560527209 03:45:40 INFO - PID 1654 | |4;prototype.html;386.77971001490306;459.8850287428144;464.9279361698936;465.5205138707131;479.51329400658335;6184.319724830265;6582.374674405935;6601.603903765771;6665.466762658988;6673.198236476149;5848.327442344132;6215.471684906788;6440.581362211453;6473.190851143607;6481;4263.786810659471;4548.976480291924;4697.741624210667;4713.451519283891;4788.0663270662235;4728.196206644861;4801.255805350159;4806.759662016902;4827.8310259140935;4828.251620998733;4219.662427005847;4771.236602143657;4790.281457781343;4803.7357945313;4815.566599006089;4185.560516145806;4382.693211475166;4773.57037866592;4813.157697402976;4838.943449034146;4210.5578914213875;4210.831566737327;4227.704060715751;4238.936415953764;4245;6355.523335749791;6369.267534233576;6378.4578310843635;6380.7128679209545;6397.168368112162;4769.236922092483;5218.921716174261;5743.253377060997;5751.281089863767;5759.452851979097;5460.781568737246;6335.904961425582;6931.376176144121;6937.340952609494;6957.686904089328;4199.958000419987;4703.612434331881;4801.663883528155;4818.397700287464;4823.397075365579;6315.8736825263695;6821;6995.755148569776;7093.787186384417;7108.395786358165;4357.302831546937;4872.3909511311085;4878.487758785347;4880;4880.829170978998;3758.962410375861;4789.137955168034;4798.424189097331;4978.5768209701855;4990.6257030722845;4406.096750166208;5344.893102137936;5502.58730595199;5650.4349565043985;5687.601867869293;6306.432421082125;6788.830279243059;6880.827979300458;6923.788337041098;6935.029095926574;1703.4719237036559;1704.8295170482852;1709.1881356355632;1709.2991873332123;1715.1595718098042;1022.4580972084807;1115.7489564847845;1133.9603113890973;1159.6173262823247;1161.616666500053;6480.546361754727;6483.124778154983;6540.869182616321;6544.292100596831;6558.704858281389;6105;6244.875102497925;6894.931050689529;7085.53943993649;7189.388901943289;6445.230529408204;6465.741370345228;6469.838254043687;6476.222853257639;6481.675916204171;2774.902878399246;3482.3534114073486;3677.0072080538107;3695.5195824543175;3726.366517691999;6152.446279834837;6397.840053998687;7268.745593904188;7298.452616053711;7355.190928997806;7318.451116166309;7320.560766354036;7322.96338518304;7331.9266807332315;7355.190928997806;7221.566705997657;7261.824450940625;7275.090613673291;7294.1035330814375;7294.379977701955;6753.15585551806;7075.646217689198;7089.136420650861;7103.467239957127;7116.43068554504;5308.849915784113;5533.695646739468;6949.235584085847;7050.788476345718;7056.329648683367;5448.863778405572;6837.829054273683;6893.620850853251;7202.603856787718;7279.599622020628;3736.1967177057063;4504.211762941538;4811.326414302071;4867.95132048675;4872;6542.934570654233;6654.733810647714;6971.198312194163;7075.780519863347;7169.996200532034;5749.856253593526;6910.378065974087;6933.7226510939;6941.93058069433;6948.096747422803;6023.849403764941;6487.9026814598765;6862.965685171542;6905.965470172617;6910.896336555056;5574.885022995336;5744.913826292525;6775.898361524482;6785.6946437411425;6853.897191542231;5869.266341707286;6171.722272497838;6357.615894039735;6817.693203805939;6882.5870447773295;6256.779927914013;6680.565763225569;6717.328267173439;6742.831429214309;6758.966205168943 03:45:40 INFO - PID 1654 | |5;yui.html;814.3078383374136;817.3420396580754;832.7793134819274;840.5377042626558;855.0167307596265;14633.853661463381;15126.941114122015;16827.41104061358;17350.826491735093;17413.390531331406;17578.736318955227;18023.008734519597;18077.728834067468;18324.725129123075;18355;970.2335155227362;989.2481713897417;994.7612572982468;1007.8236308645994;1013.3362647465922;677.4918810891832;1020.4132623741341;1022.4785359466651;1022.8772547294335;1023.912967397772;737.7934178430048;997.8852431970307;1044.5978298355112;1045.4877110215978;1053.5785685725732;1031.2008193649945;1042.5621239079605;1045.7856139491387;1047.1622701838537;1048.3709774135457;771.3058247577226;773.8684423647937;774.6978678315461;775.9922400775977;776.6699152860068;14147.646308842259;14239.572812815528;16911.83088169115;17168.914155429142;17182.484525464257;17290.740638890427;17305.221265042976;17428.47714568565;17830.37593684228;17929.7310540342;14426.062305950185;14791.334389952477;14856.480023199245;14928.925355373154;14932;3452.9309413811584;3472.670096340869;3496.702780263681;3508.017755028596;3511.227529943434;137710;139713.3014334932;139994;140404.08585699453;143040;12273.447694853574;12350.938245308716;12508.249505029728;12536.435860386122;12574.81137782934;21166.576668466852;21648.458788530414;21833.235836745636;22138.88930555369;22169.778302217095;5570.164475328652;6159.538034647419;7438.6280685965485;7688.692452301957;7767.145613982458;3913.158670885716;3982.2234664240773;4029.5366032906004;4035.6771458283856;4036.8990775230855;4484.686071974996;4538.410006699108;4557.794899229542;4600.217962946307;4604.700694454849;942.2603256443659;949.6960972488737;952.9475878826731;954.355809828377;955.2310390136007;651.2705769538053;657.4937298280297;661.6757788683607;669.8291935556402;680.1973671068187;11277.94361028206;11869.643910682693;12234.571789987307;12339.75320493603;12513.436895339732;595.6872641863057;618.8607563298312;654.3881470824793;673.4578664175386;696.8571442854203;5258.342707161605;5654.8848272533305;5683.318001839806;5903.527717782567;6046.18376519173;98337.50831245798;124942.3752881248;134971.32514337366;138127;138281.23437531193;77761;100472;101862;103977;106998;94919.52540237393;95833.52083239678;96811;99476.5026174879;103151.45272820914;11049.17131215146;11186.888131118585;11982.520699172284;12138.696532586757;12652.240865548096;39853.40219896646;45692.77153614211;46451.53548464472;47205.29192062191;47385.763071184425;79685.60157199176;79687;82181.02638886172;83685.16314836773;89218;14878.85121148818;17388.565285867953;17396.39112631052;17598.29606815713;18304.81695183031;13626.93186534061;17472.03903785304;18686.906565467085;19286.8071319285;19621.215151393786;17150.571235719206;17308;18411.079446027914;18543.72184417208;18691.252349906394;17215.7417638738;18169.36407225741;18191.18139683691;18297.908510457364;18598.628027439645;17273.740893886854;17726.113694314976;17873.463796086136;17894.642107158044;17982.640347193246;9664.468454235084;12043.518259269531;12212;12303.876961230273;12603.810942835682;13292.534761283308;14156.009079364345;14344.686269048561;14410.855891440951;14515.637109072357 03:45:40 INFO - PID 1654 | __end_tp_report 03:45:40 INFO - PID 1654 | __start_cc_report 03:45:40 INFO - PID 1654 | _x_x_mozilla_cycle_collect,467 03:45:40 INFO - PID 1654 | __end_cc_report 03:45:40 INFO - PID 1654 | __startTimestamp1497091540187__endTimestamp 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | ------- Summary: start ------- 03:45:40 INFO - PID 1654 | Number of tests: 6 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6214.69 Median:6446.84 stddev:772.65 (12.0%) stddev-sans-first:732.34 03:45:40 INFO - PID 1654 | Values: 2986.3 3629.6 3636.3 3656.2 3663.2 4770.0 4881.8 5724.1 6142.1 6300.5 6198.9 6262.1 6274.9 6282.0 6314.5 5291.4 5436.8 5505.4 5521.2 5596.3 5597.2 5601.7 5607.8 5608.2 5630.7 5400.4 5453.2 5498.8 5512.3 5528.9 5511.8 5517.5 5546.0 5548.4 5581.1 5466.0 5475.6 5494.2 5496.4 5510.6 6199.9 6233.9 6283.7 6286.6 6324.4 6789.7 6813.7 6814.5 6838.0 6847.9 6876.4 6889.6 6897.2 6916.7 7025.1 6296.0 6296.3 6371.1 6373.9 6390.3 7007.6 7032.2 7040.5 7054.4 7060.5 6432.5 6514.6 6580.3 6680.6 6687.3 6881.8 6885.5 6891.6 6897.5 6915.8 6486.4 6566.2 6646.8 6758.1 6776.2 4871.8 6717.9 6775.2 6780.4 6816.8 5385.4 5421.8 5426.8 5432.9 5475.1 4596.5 4722.7 4787.7 4817.3 4845.1 6240.5 6305.3 6356.4 6363.1 6368.1 6896.8 6900.4 6914.3 6917.0 6935.3 6329.1 6331.2 6365.3 6384.8 6390.0 5869.1 5980.4 6070.2 6073.5 6117.3 7020.8 7035.2 7038.0 7044.1 7052.3 6881.8 6888.6 6897.7 6939.4 6948.6 5983.4 6176.4 6553.7 6567.8 6681.8 6065.5 6734.7 6773.6 6781.2 6794.4 5579.7 6656.2 6882.3 6892.8 6900.4 6536.9 6604.7 6638.0 6644.8 6729.5 6656.5 6667.8 6680.4 6680.7 6682.3 6345.5 6446.8 6520.0 6525.6 6527.2 6490.2 6581.4 6639.1 6644.5 6688.9 6470.2 6471.8 6515.3 6523.1 6539.3 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:42093.95 Median:32791.84 stddev:72966.51 (222.5%) stddev-sans-first:73104.90 03:45:40 INFO - PID 1654 | Values: 666.9 691.8 832.2 1284.6 1311.2 35480.6 36524.0 36569.0 36598.3 36608.0 37112.8 37376.4 37390.4 37451.4 37715.8 7638.7 7734.9 7748.1 7755.6 7759.4 7717.9 7795.7 7806.4 7810.3 7826.7 7771.7 7994.1 8216.4 8221.3 8221.4 8173.7 8176.7 8219.1 8223.2 8230.1 7953.1 8021.7 8047.6 8054.0 8066.4 34484.0 34864.8 34913.7 34924.8 35027.5 3578.8 3581.3 3622.3 3623.9 3625.2 29026.6 29109.6 29132.8 29171.4 29174.4 2573.3 3051.3 3061.1 3062.7 3080.1 211283.0 211638.0 262442.0 268152.0 268708.0 45601.1 45637.1 45819.3 45838.0 45856.0 40050.6 45108.1 47365.8 47877.0 48000.3 13621.7 13686.6 13808.5 13817.0 13819.7 10006.3 11299.0 11301.5 11308.2 11327.2 11918.4 12785.6 12914.5 12916.7 12954.1 5264.6 5862.1 6074.4 6108.4 6118.3 910.5 982.6 1004.3 1008.4 1051.3 20609.9 20802.4 22109.3 22378.3 22649.7 1090.6 1098.7 1144.9 1149.8 1157.4 9952.7 10961.8 11785.5 12161.9 12282.4 278588.0 323490.0 324349.0 324423.0 324550.0 51266.2 51487.5 51574.0 51658.5 51710.7 1370.3 1427.2 1500.8 1527.6 1534.3 18032.7 21620.5 22257.8 22460.6 23589.8 36624.0 36685.0 36690.1 36710.1 36719.0 267211.0 267797.0 269422.0 269424.0 270008.0 31623.8 32768.3 32815.3 32996.7 33123.5 34241.3 41905.0 42966.8 42994.8 43157.4 23465.8 28535.4 28622.3 29053.9 30119.4 28373.9 29756.6 29857.6 30027.2 30561.4 34250.8 35263.6 35432.8 35550.3 36028.6 29367.6 29643.9 30726.4 30838.8 31207.0 22604.0 23517.3 23691.9 23946.8 24098.2 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:39115.87 Median:22187.06 stddev:94850.26 (427.5%) stddev-sans-first:95071.88 03:45:40 INFO - PID 1654 | Values: 432.9 478.8 480.2 487.4 499.9 21499.1 21791.2 22754.5 22815.9 24616.3 24333.6 24956.4 24971.4 25012.5 25031.4 6296.1 6421.6 6432.7 6441.9 6460.3 6111.2 6396.2 6409.8 6461.1 6474.5 6344.6 6378.6 6419.4 6426.2 6444.0 6412.9 6435.8 6437.0 6443.1 6454.2 5844.4 5845.2 5879.4 5895.7 5896.5 22215.2 24537.0 30232.7 30460.8 30531.8 1938.5 2249.4 2260.6 2269.1 2271.9 18721.3 18952.0 19047.6 19051.4 19077.8 1946.9 2065.4 2200.9 2237.2 2246.9 173028.0 180212.0 202675.0 204990.0 211301.0 14622.8 15156.0 15214.8 15452.4 15570.5 20393.5 20463.8 20482.7 20513.0 20524.3 8291.0 9883.1 9912.7 9918.0 9920.7 7397.0 9089.3 9091.3 9144.3 9172.7 9176.0 9187.9 9236.0 9250.8 9303.3 2197.9 2234.8 2617.4 2631.7 2746.0 887.7 968.6 970.4 1010.4 1022.2 17580.6 21201.0 22158.9 22302.3 22453.9 987.7 1004.7 1026.4 1038.7 1044.6 10722.6 10818.6 10836.7 11334.6 11606.3 511621.0 546125.3 546257.0 552214.0 558872.0 150402.2 151527.0 151752.0 160150.0 163524.0 1163.9 1178.5 1188.9 1194.1 1195.3 18061.7 18290.4 18524.0 18931.2 19461.9 68715.7 69985.3 71280.0 71538.6 71872.0 94283.1 94306.0 95488.0 95514.0 95661.6 11221.5 11302.8 11382.3 11517.3 11535.7 18066.0 18217.1 18459.9 18509.5 18550.3 14697.2 14938.7 15005.8 15025.2 15088.5 13256.9 13258.9 13273.7 13643.5 13833.9 14791.6 14912.9 15034.8 15228.7 15579.8 10088.8 11168.2 11395.0 11426.9 11468.5 14695.8 14871.0 14939.9 15019.2 15054.8 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:8138.09 Median:2739.29 stddev:5384.60 (196.6%) stddev-sans-first:5375.16 03:45:40 INFO - PID 1654 | Values: 1296.4 1424.9 1425.6 1450.7 1463.5 12869.2 13294.6 13306.7 13436.5 13755.9 13215.5 13416.8 13423.7 13428.7 13438.3 2696.0 2735.2 2738.5 2753.5 2765.8 2683.3 2729.9 2740.1 2743.5 2747.5 2289.0 2689.7 2719.0 2719.4 2730.6 2647.2 2678.3 2685.9 2689.3 2690.6 2577.8 2646.1 2663.0 2684.0 2766.5 13110.2 13226.3 13226.9 13251.5 13258.5 7325.5 7592.0 7607.4 7622.2 7647.3 4559.7 4693.9 4740.9 4746.3 4830.7 1192.3 1437.6 1455.8 1462.4 1464.4 19747.9 19820.8 19940.7 19947.6 19979.6 6350.7 6431.6 6548.7 6647.8 6655.5 14223.8 14223.9 14225.6 14226.3 14239.4 7260.4 7316.9 7331.5 7407.0 7423.9 6624.9 6763.5 6815.9 6820.7 6905.4 4182.9 4310.0 4766.6 5002.8 5034.3 1466.5 1496.8 1500.4 1512.7 1515.8 741.5 806.5 832.9 850.2 858.3 7543.8 7801.0 7812.0 7949.6 8171.1 809.8 847.3 970.7 1012.5 1015.7 4581.1 4883.6 5094.5 5131.6 5266.9 17506.3 17747.6 17896.0 18151.2 18337.0 17511.3 17835.6 18137.0 18258.8 18290.9 7976.6 9195.8 9297.8 9368.0 9426.8 6770.6 7063.6 7484.9 7652.4 7957.4 14720.5 15895.3 16116.4 16247.8 16277.7 15710.3 16208.6 16437.3 16690.4 16693.4 9574.2 9606.8 9733.8 9775.0 9856.2 12026.5 12109.3 12135.2 12253.8 12288.4 9804.1 10042.6 10178.9 10194.0 10212.3 9848.9 9859.6 9874.0 9905.9 10035.5 8904.4 9204.5 9365.8 9367.3 9384.4 5695.0 6560.3 6597.4 6625.4 7035.1 6584.7 6601.5 6844.4 6955.7 6993.4 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5538.84 Median:6250.83 stddev:1716.85 (27.5%) stddev-sans-first:1677.56 03:45:40 INFO - PID 1654 | Values: 386.8 459.9 464.9 465.5 479.5 6184.3 6582.4 6601.6 6665.5 6673.2 5848.3 6215.5 6440.6 6473.2 6481.0 4263.8 4549.0 4697.7 4713.5 4788.1 4728.2 4801.3 4806.8 4827.8 4828.3 4219.7 4771.2 4790.3 4803.7 4815.6 4185.6 4382.7 4773.6 4813.2 4838.9 4210.6 4210.8 4227.7 4238.9 4245.0 6355.5 6369.3 6378.5 6380.7 6397.2 4769.2 5218.9 5743.3 5751.3 5759.5 5460.8 6335.9 6931.4 6937.3 6957.7 4200.0 4703.6 4801.7 4818.4 4823.4 6315.9 6821.0 6995.8 7093.8 7108.4 4357.3 4872.4 4878.5 4880.0 4880.8 3759.0 4789.1 4798.4 4978.6 4990.6 4406.1 5344.9 5502.6 5650.4 5687.6 6306.4 6788.8 6880.8 6923.8 6935.0 1703.5 1704.8 1709.2 1709.3 1715.2 1022.5 1115.7 1134.0 1159.6 1161.6 6480.5 6483.1 6540.9 6544.3 6558.7 6105.0 6244.9 6894.9 7085.5 7189.4 6445.2 6465.7 6469.8 6476.2 6481.7 2774.9 3482.4 3677.0 3695.5 3726.4 6152.4 6397.8 7268.7 7298.5 7355.2 7318.5 7320.6 7323.0 7331.9 7355.2 7221.6 7261.8 7275.1 7294.1 7294.4 6753.2 7075.6 7089.1 7103.5 7116.4 5308.8 5533.7 6949.2 7050.8 7056.3 5448.9 6837.8 6893.6 7202.6 7279.6 3736.2 4504.2 4811.3 4868.0 4872.0 6542.9 6654.7 6971.2 7075.8 7170.0 5749.9 6910.4 6933.7 6941.9 6948.1 6023.8 6487.9 6863.0 6906.0 6910.9 5574.9 5744.9 6775.9 6785.7 6853.9 5869.3 6171.7 6357.6 6817.7 6882.6 6256.8 6680.6 6717.3 6742.8 6759.0 03:45:40 INFO - PID 1654 | 03:45:40 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:24428.17 Median:18244.54 stddev:36301.97 (199.0%) stddev-sans-first:36360.51 03:45:40 INFO - PID 1654 | Values: 814.3 817.3 832.8 840.5 855.0 14633.9 15126.9 16827.4 17350.8 17413.4 17578.7 18023.0 18077.7 18324.7 18355.0 970.2 989.2 994.8 1007.8 1013.3 677.5 1020.4 1022.5 1022.9 1023.9 737.8 997.9 1044.6 1045.5 1053.6 1031.2 1042.6 1045.8 1047.2 1048.4 771.3 773.9 774.7 776.0 776.7 14147.6 14239.6 16911.8 17168.9 17182.5 17290.7 17305.2 17428.5 17830.4 17929.7 14426.1 14791.3 14856.5 14928.9 14932.0 3452.9 3472.7 3496.7 3508.0 3511.2 137710.0 139713.3 139994.0 140404.1 143040.0 12273.4 12350.9 12508.2 12536.4 12574.8 21166.6 21648.5 21833.2 22138.9 22169.8 5570.2 6159.5 7438.6 7688.7 7767.1 3913.2 3982.2 4029.5 4035.7 4036.9 4484.7 4538.4 4557.8 4600.2 4604.7 942.3 949.7 952.9 954.4 955.2 651.3 657.5 661.7 669.8 680.2 11277.9 11869.6 12234.6 12339.8 12513.4 595.7 618.9 654.4 673.5 696.9 5258.3 5654.9 5683.3 5903.5 6046.2 98337.5 124942.4 134971.3 138127.0 138281.2 77761.0 100472.0 101862.0 103977.0 106998.0 94919.5 95833.5 96811.0 99476.5 103151.5 11049.2 11186.9 11982.5 12138.7 12652.2 39853.4 45692.8 46451.5 47205.3 47385.8 79685.6 79687.0 82181.0 83685.2 89218.0 14878.9 17388.6 17396.4 17598.3 18304.8 13626.9 17472.0 18686.9 19286.8 19621.2 17150.6 17308.0 18411.1 18543.7 18691.3 17215.7 18169.4 18191.2 18297.9 18598.6 17273.7 17726.1 17873.5 17894.6 17982.6 9664.5 12043.5 12212.0 12303.9 12603.8 13292.5 14156.0 14344.7 14410.9 14515.6 03:45:40 INFO - PID 1654 | -------- Summary: end -------- 03:45:40 INFO - PID 1654 | 03:45:40 INFO - TEST-INFO | 1654: exit 0 03:45:40 INFO - TEST-OK | dromaeo_css | took 1142879ms 03:45:40 INFO - TEST-START | kraken 03:45:40 INFO - operating with platform_type : mac_ 03:45:40 INFO - Initialising browser for kraken test... 03:45:40 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/tmpPqE91g/profile 03:45:40 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 03:46:14 INFO - TEST-INFO | 1686: killed by SIGTERM 03:46:14 INFO - Browser initialized. 03:46:14 INFO - Running cycle 1/1 for kraken test... 03:46:14 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/T/tmpPqE91g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:46:16 INFO - PID 1690 | 2017-06-10 03:46:16.673 plugin-container[1691:11280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 03:46:16 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:46:16 INFO - PID 1690 | 2017-06-10 03:46:16.673 plugin-container[1691:11280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 03:46:16 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:46:16 INFO - PID 1690 | 2017-06-10 03:46:16.673 plugin-container[1691:11280] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:46:17 INFO - PID 1690 | 2017-06-10 03:46:17.484 plugin-container[1692:11326] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 03:46:17 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:46:17 INFO - PID 1690 | 2017-06-10 03:46:17.485 plugin-container[1692:11326] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490b, name = 'com.apple.CFPasteboardClient' 03:46:17 INFO - PID 1690 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:46:17 INFO - PID 1690 | 2017-06-10 03:46:17.485 plugin-container[1692:11326] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:46:26 INFO - PID 1690 | RSS: Main: 253145088 03:46:26 INFO - PID 1690 | 03:46:29 INFO - PID 1690 | 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) 03:46:30 INFO - PID 1690 | RSS: Main: 266575872 03:46:30 INFO - PID 1690 | 03:46:33 INFO - PID 1690 | 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) 03:46:33 INFO - PID 1690 | RSS: Main: 275595264 03:46:33 INFO - PID 1690 | 03:46:37 INFO - PID 1690 | 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) 03:46:38 INFO - PID 1690 | RSS: Main: 278106112 03:46:38 INFO - PID 1690 | 03:46:40 INFO - PID 1690 | 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) 03:46:40 INFO - PID 1690 | RSS: Main: 280117248 03:46:40 INFO - PID 1690 | 03:46:42 INFO - PID 1690 | 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) 03:46:43 INFO - PID 1690 | RSS: Main: 280801280 03:46:43 INFO - PID 1690 | 03:46:46 INFO - PID 1690 | 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) 03:46:46 INFO - PID 1690 | RSS: Main: 288260096 03:46:46 INFO - PID 1690 | 03:46:49 INFO - PID 1690 | 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) 03:46:50 INFO - PID 1690 | RSS: Main: 287137792 03:46:50 INFO - PID 1690 | 03:46:53 INFO - PID 1690 | 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) 03:46:54 INFO - PID 1690 | RSS: Main: 285855744 03:46:54 INFO - PID 1690 | 03:46:55 INFO - PID 1690 | 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) 03:46:56 INFO - PID 1690 | RSS: Main: 286330880 03:46:56 INFO - PID 1690 | 03:46:57 INFO - PID 1690 | 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) 03:46:58 INFO - PID 1690 | RSS: Main: 285704192 03:46:58 INFO - PID 1690 | 03:47:00 INFO - PID 1690 | 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) 03:47:01 INFO - PID 1690 | RSS: Main: 285503488 03:47:01 INFO - PID 1690 | 03:47:04 INFO - PID 1690 | 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) 03:47:05 INFO - PID 1690 | RSS: Main: 285200384 03:47:05 INFO - PID 1690 | 03:47:08 INFO - PID 1690 | 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) 03:47:08 INFO - PID 1690 | RSS: Main: 285749248 03:47:08 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | 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) 03:47:10 INFO - PID 1690 | RSS: Main: 286740480 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | __start_tp_report 03:47:10 INFO - PID 1690 | _x_x_mozilla_page_load 03:47:10 INFO - PID 1690 | _x_x_mozilla_page_load_details 03:47:10 INFO - PID 1690 | |i|pagename|runs| 03:47:10 INFO - PID 1690 | |0;ai-astar;117;150;98;116;164;148;116;116;119;119 03:47:10 INFO - PID 1690 | |1;audio-beat-detection;133;113;136;154;116;108;136;134;204;148 03:47:10 INFO - PID 1690 | |2;audio-dft;200;200;201;209;211;240;277;250;196;196 03:47:10 INFO - PID 1690 | |3;audio-fft;72;85;77;145;59;57;56;67;125;59 03:47:10 INFO - PID 1690 | |4;audio-oscillator;69;72;112;71;72;75;143;105;105;120 03:47:10 INFO - PID 1690 | |5;imaging-gaussian-blur;89;89;88;88;88;88;100;89;89;90 03:47:10 INFO - PID 1690 | |6;imaging-darkroom;96;97;96;96;98;96;96;96;97;102 03:47:10 INFO - PID 1690 | |7;imaging-desaturate;92;90;92;90;93;90;89;94;126;130 03:47:10 INFO - PID 1690 | |8;json-parse-financial;49;49;53;52;54;53;55;53;54;53 03:47:10 INFO - PID 1690 | |9;json-stringify-tinderbox;54;55;57;63;57;60;55;57;56;57 03:47:10 INFO - PID 1690 | |10;stanford-crypto-aes;84;87;83;84;86;83;88;91;112;87 03:47:10 INFO - PID 1690 | |11;stanford-crypto-ccm;137;136;134;166;146;137;148;139;144;157 03:47:10 INFO - PID 1690 | |12;stanford-crypto-pbkdf2;175;180;185;225;217;181;181;188;181;177 03:47:10 INFO - PID 1690 | |13;stanford-crypto-sha256-iterative;68;69;67;66;68;69;63;66;65;65 03:47:10 INFO - PID 1690 | __end_tp_report 03:47:10 INFO - PID 1690 | __start_cc_report 03:47:10 INFO - PID 1690 | _x_x_mozilla_cycle_collect,1178 03:47:10 INFO - PID 1690 | __end_cc_report 03:47:10 INFO - PID 1690 | __startTimestamp1497091630665__endTimestamp 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | ------- Summary: start ------- 03:47:10 INFO - PID 1690 | Number of tests: 14 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#0] ai-astar Cycles:10 Average:126.30 Median:133.50 stddev:20.46 (15.3%) stddev-sans-first:21.42 03:47:10 INFO - PID 1690 | Values: 117.0 150.0 98.0 116.0 164.0 148.0 116.0 116.0 119.0 119.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:136.00 stddev:27.43 (20.2%) stddev-sans-first:29.02 03:47:10 INFO - PID 1690 | Values: 133.0 113.0 136.0 154.0 116.0 108.0 136.0 134.0 204.0 148.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#2] audio-dft Cycles:10 Average:218.00 Median:210.00 stddev:27.94 (13.3%) stddev-sans-first:28.86 03:47:10 INFO - PID 1690 | Values: 200.0 200.0 201.0 209.0 211.0 240.0 277.0 250.0 196.0 196.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#3] audio-fft Cycles:10 Average:80.20 Median:63.00 stddev:30.74 (48.8%) stddev-sans-first:32.46 03:47:10 INFO - PID 1690 | Values: 72.0 85.0 77.0 145.0 59.0 57.0 56.0 67.0 125.0 59.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:70.00 stddev:26.09 (37.3%) stddev-sans-first:26.00 03:47:10 INFO - PID 1690 | Values: 69.0 72.0 112.0 71.0 72.0 75.0 143.0 105.0 105.0 120.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:89.00 stddev:3.65 (4.1%) stddev-sans-first:3.86 03:47:10 INFO - PID 1690 | Values: 89.0 89.0 88.0 88.0 88.0 88.0 100.0 89.0 89.0 90.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:96.00 stddev:1.89 (2.0%) stddev-sans-first:1.96 03:47:10 INFO - PID 1690 | Values: 96.0 97.0 96.0 96.0 98.0 96.0 96.0 96.0 97.0 102.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#7] imaging-desaturate Cycles:10 Average:98.60 Median:91.00 stddev:15.60 (17.1%) stddev-sans-first:16.36 03:47:10 INFO - PID 1690 | Values: 92.0 90.0 92.0 90.0 93.0 90.0 89.0 94.0 126.0 130.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:2.01 (3.8%) stddev-sans-first:1.69 03:47:10 INFO - PID 1690 | Values: 49.0 49.0 53.0 52.0 54.0 53.0 55.0 53.0 54.0 53.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:57.00 stddev:2.64 (4.6%) stddev-sans-first:2.55 03:47:10 INFO - PID 1690 | Values: 54.0 55.0 57.0 63.0 57.0 60.0 55.0 57.0 56.0 57.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:86.50 stddev:8.63 (10.0%) stddev-sans-first:9.00 03:47:10 INFO - PID 1690 | Values: 84.0 87.0 83.0 84.0 86.0 83.0 88.0 91.0 112.0 87.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:145.00 stddev:10.32 (7.1%) stddev-sans-first:10.59 03:47:10 INFO - PID 1690 | Values: 137.0 136.0 134.0 166.0 146.0 137.0 148.0 139.0 144.0 157.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:183.00 stddev:17.35 (9.5%) stddev-sans-first:17.65 03:47:10 INFO - PID 1690 | Values: 175.0 180.0 185.0 225.0 217.0 181.0 181.0 188.0 181.0 177.0 03:47:10 INFO - PID 1690 | 03:47:10 INFO - PID 1690 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.50 stddev:1.96 (2.9%) stddev-sans-first:2.01 03:47:10 INFO - PID 1690 | Values: 68.0 69.0 67.0 66.0 68.0 69.0 63.0 66.0 65.0 65.0 03:47:10 INFO - PID 1690 | -------- Summary: end -------- 03:47:10 INFO - PID 1690 | 03:47:11 INFO - TEST-INFO | 1690: exit 0 03:47:11 INFO - TEST-OK | kraken | took 90694ms 03:47:11 INFO - SUITE-END | took 1233s 03:47:11 INFO - Completed test suite (00:20:33) 03:47:11 INFO - javascript benchmark 03:47:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8244.802344474023, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2986.3430045390014, 3629.6370362963708, 3636.2727454509104, 3656.1956369598697, 3663.230721548475, 4769.952300476994, 4881.804727810883, 5724.141378793178, 6142.109394137848, 6300.5274604404685, 6198.876022479559, 6262.12330273762, 6274.937250627492, 6281.968590157043, 6314.463270622003, 5291.391489978663, 5436.782528698847, 5505.449455054492, 5521.171824226358, 5596.3284405871145, 5597.188407680884, 5601.691906945116, 5607.803726869561, 5608.214849920994, 5630.7184640768, 5400.405955344909, 5453.209284653703, 5498.752556134984, 5512.33851937768, 5528.889422211573, 5511.807086751945, 5517.503424691797, 5545.972270138624, 5548.389677135512, 5581.107022876361, 5466.0, 5475.589330800166, 5494.203340515603, 5496.422875598085, 5510.559155267569, 6199.87600247997, 6233.937660623381, 6283.748650053993, 6286.559940804145, 6324.3991820776955, 6789.694463749143, 6813.693383797741, 6814.522983391164, 6837.965810170967, 6847.897281540781, 6876.415504682058, 6889.621070841102, 6897.206821215575, 6916.688749006306, 7025.051618031552, 6296.0, 6296.275928268216, 6371.076193951943, 6373.904391434132, 6390.265119511269, 7007.579545227302, 7032.191298000693, 7040.5071644984355, 7054.365107140279, 7060.470464715167, 6432.485401167895, 6514.57655252407, 6580.309067547934, 6680.565763225375, 6687.297833727389, 6881.793546193622, 6885.51801373899, 6891.552049116892, 6897.517173797886, 6915.757948471779, 6486.383793539606, 6566.212054553484, 6646.767363142267, 6758.05387245786, 6776.220734615582, 4871.756412179377, 6717.899231511531, 6775.220849602359, 6780.355866192704, 6816.795496135124, 5385.380681221632, 5421.783128674809, 5426.75579598919, 5432.864178395493, 5475.123980163155, 4596.49438561758, 4722.716637001791, 4787.736674482867, 4817.2774083887, 4845.103655823684, 6240.500759939194, 6305.3379395163765, 6356.396142366468, 6363.109164716943, 6368.10846481493, 6896.7586134485055, 6900.413464855544, 6914.308569143146, 6917.031615573824, 6935.271796461299, 6329.145565348711, 6331.176946996861, 6365.3316401778075, 6384.776532821329, 6389.9680501597195, 5869.119632055226, 5980.372060933626, 6070.180525629072, 6073.544484163617, 6117.279373342116, 7020.824479388056, 7035.1909530404655, 7037.964810175917, 7044.119485064367, 7052.294770523009, 6881.827954300982, 6888.552244104118, 6897.655117244218, 6939.444844412334, 6948.583085014713, 5983.401659833982, 6176.4441200292185, 6553.672316384066, 6567.802965911031, 6681.799546013627, 6065.4541091302, 6734.6632668367365, 6773.559718618274, 6781.220159681699, 6794.422474089758, 5579.69311687861, 6656.201255849329, 6882.346177113166, 6892.827679308058, 6900.447964162955, 6536.934630653633, 6604.669766511752, 6638.0, 6644.83387915306, 6729.528932974645, 6656.534042617164, 6667.766628167991, 6680.3987641112535, 6680.665966701743, 6682.3317668232785, 6345.46063584582, 6446.8388290293115, 6519.967400162969, 6525.64108974011, 6527.216733991761, 6490.188726409199, 6581.3747693969, 6639.103720997797, 6644.468442524683, 6688.933110668831, 6470.158879345655, 6471.838204044937, 6515.250746164252, 6523.086767852409, 6539.280679125197], "value": 6162.256925444463, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.8532922756995, 691.7962744824006, 832.1636755061164, 1284.608194500677, 1311.2001678975817, 35480.64519354806, 36524.0, 36569.0, 36598.26803463929, 36607.999999999935, 37112.81443592785, 37376.43935340965, 37390.43914341287, 37451.438228426596, 37715.811420942926, 7638.656260468279, 7734.922650773477, 7748.147703752611, 7755.612219389036, 7759.41804364672, 7717.884231736529, 7795.688172473095, 7806.375489960789, 7810.258025487598, 7826.72606458774, 7771.689132434724, 7994.080680721704, 8216.424850260484, 8221.342292616606, 8221.424500284982, 8173.6730530779, 8176.713815016446, 8219.136990615989, 8223.21879421457, 8230.135835737221, 7953.12515623281, 8021.67913283468, 8047.597620119029, 8053.959730201312, 8066.4353495255355, 34483.96548103561, 34864.82567587171, 34913.6508634913, 34924.82537587321, 35027.4745878812, 3578.7673801202836, 3581.301646178996, 3622.275544891006, 3623.9094022649383, 3625.202455459821, 29026.564601530994, 29109.56335654967, 29132.83468661251, 29171.41657166866, 29174.41651166965, 2573.2923446052487, 3051.313454472748, 3061.11227743954, 3062.693730626942, 3080.091373044957, 211283.0, 211638.0, 262442.0, 268152.0, 268708.0, 45601.08797824025, 45637.08725825465, 45819.31271030937, 45838.0, 45856.0, 40050.59949400527, 45108.09783804305, 47365.76317118392, 47877.0, 48000.27999580009, 13621.659458513419, 13686.589402317946, 13808.516701915385, 13817.0, 13819.654508637364, 10006.299559030944, 11299.0, 11301.49143288554, 11308.208425410307, 11327.150463715254, 11918.404079795975, 12785.552505662257, 12914.483420663257, 12916.677083072811, 12954.093213474966, 5264.6051546134195, 5862.062070068769, 6074.362191969868, 6108.358622344679, 6118.265808103056, 910.535626830308, 982.5529384130203, 1004.3070281505737, 1008.4050410257983, 1051.295631926611, 20609.896950515453, 20802.375928722162, 22109.33792862329, 22378.32865014052, 22649.660255096187, 1090.6182836007333, 1098.662669138815, 1144.9427528623703, 1149.7815415071277, 1157.432857899623, 9952.651657191964, 10961.83450288885, 11785.528578856922, 12161.939190303992, 12282.38588070593, 278588.0, 323490.0, 324349.0, 324423.0, 324550.0, 51266.23100653493, 51487.48512514902, 51574.0, 51658.48341516612, 51710.74144629328, 1370.3216907630785, 1427.2078996157231, 1500.7973923520403, 1527.618095476131, 1534.2942246566704, 18032.729509057113, 21620.45443392109, 22257.77742222622, 22460.550788984456, 23589.764102358757, 36624.0, 36685.0, 36690.08274793151, 36710.08224794402, 36719.0, 267211.0, 267797.0, 269422.0, 269424.0, 270008.0, 31623.84188079045, 32768.344633107685, 32815.34369312553, 32996.67003329936, 33123.50314745329, 34241.31517369689, 41905.0, 42966.78516607522, 42994.78502607467, 43157.352639711055, 23465.76534234704, 28535.42929141447, 28622.284442889093, 29053.854730726212, 30119.3976120472, 28373.85813071004, 29756.553651695674, 29857.55213671753, 30027.249318767204, 30561.388772224876, 34250.82874585611, 35263.64736352603, 35432.82283588566, 35550.288994220486, 36028.639713602526, 29367.55948660729, 29643.85178074096, 30726.385472290876, 30838.845805770827, 31207.0, 22604.0, 23517.29448116559, 23691.933862975864, 23946.76053239445, 24098.15656452016], "value": 16703.071554862418, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.9350597410388, 478.7630123089067, 480.16691041043805, 487.359122753579, 499.91501444754385, 21499.140034398646, 21791.237306694267, 22754.544909101827, 22815.885920570396, 24616.261512154662, 24333.63499547508, 24956.376090597743, 24971.37571560712, 25012.49975000499, 25031.374215644617, 6296.118543403927, 6421.582597131195, 6432.742690292383, 6441.903371449432, 6460.321666225049, 6111.236095488064, 6396.2324521057535, 6409.807705768834, 6461.06314584385, 6474.514411419139, 6344.555881088326, 6378.553501254914, 6419.390157934989, 6426.164598602199, 6443.967780161116, 6412.903806442884, 6435.806925792187, 6436.967815160918, 6443.130177426081, 6454.160959075337, 5844.415558444164, 5845.152452894306, 5879.441453061952, 5895.675737834417, 5896.469317761422, 22215.22246721373, 24537.0, 30232.69767302321, 30460.8476957616, 30531.847340763376, 1938.5056810513229, 2249.381420109483, 2260.5930932432157, 2269.0810221860174, 2271.863688178698, 18721.344752933717, 18952.0, 19047.571432142508, 19051.428457146307, 19077.80922190774, 1946.8539859510452, 2065.4423305707523, 2200.8899555022185, 2237.172246268891, 2246.898889549958, 173028.0, 180212.0, 202674.9732502671, 204990.0, 211300.99999999846, 14622.812821826901, 15156.0, 15214.847851521343, 15452.38190472391, 15570.532884013497, 20393.49016274605, 20463.795362046487, 20482.692759608617, 20513.0, 20524.281650142173, 8291.0, 9883.06110919476, 9912.702618921443, 9917.950410248048, 9920.702378928643, 7397.0, 9089.318301127441, 9091.27269818413, 9144.314176436794, 9172.724818255463, 9176.036516165706, 9187.862182067274, 9235.953820230856, 9250.768730781785, 9303.25573954082, 2197.8791166485676, 2234.832387570939, 2617.437250991046, 2631.7105118436957, 2745.958810617842, 887.7203680840514, 968.5738275159049, 970.369259981018, 1010.3836659637615, 1022.2486472442748, 17580.648387032186, 21201.0, 22158.88920555387, 22302.330930072123, 22453.887730561244, 987.688878003441, 1004.7036124343336, 1026.3995562595965, 1038.6883934819523, 1044.6187141693226, 10722.571097156182, 10818.56725730962, 10836.688064410893, 11334.60328888485, 11606.30362178272, 511621.0, 546125.2693736505, 546257.0, 552214.0, 558872.0, 150402.24798875937, 151527.0, 151752.0, 160150.0, 163524.0, 1163.8943004146095, 1178.5403692559908, 1188.851393575803, 1194.098455665984, 1195.2888031621171, 18061.729074064162, 18290.35983740616, 18523.981181034633, 18931.242750290385, 19461.90269048646, 68715.65642171758, 69985.30014699788, 71280.0, 71538.64230679022, 71872.04703741135, 94283.05716943018, 94306.0, 95488.0, 95514.0, 95661.56507652251, 11221.495032723384, 11302.773944521228, 11382.317060976367, 11517.25137866036, 11535.653930382103, 18066.0, 18217.089145542934, 18459.892406455656, 18509.53726156857, 18550.25798968026, 14697.19165445868, 14938.701225975203, 15005.774913376526, 15025.248737563297, 15088.54734357971, 13256.93371533136, 13258.86741132615, 13273.734525309246, 13643.454261829416, 13833.930830345784, 14791.556253312418, 14912.850871491146, 15034.849651503782, 15228.695426091637, 15579.844201557838, 10088.848667269851, 11168.21822472419, 11395.0, 11426.94286528562, 11468.483918223867, 14695.779563306773, 14871.0, 14939.850601494281, 15019.249037547861, 15054.849451505344], "value": 11637.402588482233, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.429570988765, 1424.8575142485752, 1425.6435891027243, 1450.673598440351, 1463.4999125895959, 12869.227846329226, 13294.601161965133, 13306.667333316673, 13436.52972145974, 13755.862441375582, 13215.537456189035, 13416.798748018788, 13423.731525369487, 13428.66428339292, 13438.260895650736, 2695.9865200674017, 2735.179446166152, 2738.4933787249324, 2753.4768394005173, 2765.764909982654, 2683.329167708073, 2729.945401091977, 2740.0957683964343, 2743.5198840202984, 2747.4505098980185, 2289.0, 2689.7041325454184, 2719.02115238514, 2719.3745438549145, 2730.604062410949, 2647.205838248537, 2678.3036410533305, 2685.8791354388904, 2689.3007817967273, 2690.5695088785897, 2577.755113264237, 2646.0606484697846, 2663.0146845667227, 2684.0203325785947, 2766.543520319165, 13110.213387196703, 13226.272555009471, 13226.933865330611, 13251.536196233086, 13258.53595124166, 7325.523840950321, 7592.0, 7607.353374963135, 7622.199669034727, 7647.3117419431965, 4559.74921379324, 4693.929591056137, 4740.857774266778, 4746.311784791221, 4830.73430961297, 1192.3263356203804, 1437.5902867682764, 1455.8034665320151, 1462.4369617697157, 1464.4288727396324, 19747.90126049375, 19820.80179198204, 19940.70088948667, 19947.601047979104, 19979.60040799176, 6350.682465876687, 6431.646259455775, 6548.70530826114, 6647.833804154838, 6655.5341126121675, 14223.786643200361, 14223.857761422254, 14225.644358890904, 14226.288685565887, 14239.359228834728, 7260.41916646667, 7316.926830731731, 7331.450141239322, 7406.962965185139, 7423.9257607424315, 6624.867502650017, 6763.492738044666, 6815.863682726318, 6820.727170913108, 6905.413039891565, 4182.853600123981, 4309.978450107792, 4766.594839438687, 5002.799888004512, 5034.345535080416, 1466.5087195789317, 1496.8428315026983, 1500.4298366620615, 1512.7125846089207, 1515.8181018277878, 741.5476559298824, 806.5040000399712, 832.904216015166, 850.2093053460342, 858.3176374782063, 7543.8114047148165, 7801.0, 7811.960940195377, 7949.6025198739835, 8171.142030086874, 809.7935026568305, 847.3306088190261, 970.7184916374314, 1012.5038731021747, 1015.7206768138673, 4581.083783243364, 4883.609311255091, 5094.465081166498, 5131.589472842238, 5266.8946621068135, 17506.299748009937, 17747.645047098984, 17896.0, 18151.183196756177, 18336.991465469262, 17511.299548017938, 17835.643287134444, 18137.0, 18258.817411825978, 18290.908545457187, 7976.641051152712, 9195.816083678288, 9297.767555811159, 9368.016358282419, 9426.811463770686, 6770.593764374154, 7063.646817659199, 7484.887726683995, 7652.426068045031, 7957.363410926996, 14720.484783032542, 15895.284712188211, 16116.355345786038, 16247.837521625106, 16277.67444651124, 15710.293036813142, 16208.594785130466, 16437.260323285722, 16690.4158354457, 16693.415730449375, 9574.234061275221, 9606.799150106237, 9733.756656083655, 9774.951125244328, 9856.162226210852, 12026.518939242682, 12109.33398663082, 12135.150539462153, 12253.754924901274, 12288.38558072075, 9804.097771387593, 10042.648507302209, 10178.931212222906, 10194.0, 10212.285140040125, 9848.901510984799, 9859.556319965475, 9874.012598740355, 9905.900940990497, 10035.54840032215, 8904.376693631384, 9204.539773011456, 9365.765855853659, 9367.250619950251, 9384.436933783994, 5695.0, 6560.3111673273615, 6597.439217666552, 6625.436837868835, 7035.05026821393, 6584.670766461562, 6601.504887133388, 6844.4182244509775, 6955.686994085379, 6993.405560527209], "value": 6027.111791810765, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.77971001490306, 459.8850287428144, 464.9279361698936, 465.5205138707131, 479.51329400658335, 6184.319724830265, 6582.374674405935, 6601.603903765771, 6665.466762658988, 6673.198236476149, 5848.327442344132, 6215.471684906788, 6440.581362211453, 6473.190851143607, 6481.0, 4263.786810659471, 4548.976480291924, 4697.741624210667, 4713.451519283891, 4788.0663270662235, 4728.196206644861, 4801.255805350159, 4806.759662016902, 4827.8310259140935, 4828.251620998733, 4219.662427005847, 4771.236602143657, 4790.281457781343, 4803.7357945313, 4815.566599006089, 4185.560516145806, 4382.693211475166, 4773.57037866592, 4813.157697402976, 4838.943449034146, 4210.5578914213875, 4210.831566737327, 4227.704060715751, 4238.936415953764, 4245.0, 6355.523335749791, 6369.267534233576, 6378.4578310843635, 6380.7128679209545, 6397.168368112162, 4769.236922092483, 5218.921716174261, 5743.253377060997, 5751.281089863767, 5759.452851979097, 5460.781568737246, 6335.904961425582, 6931.376176144121, 6937.340952609494, 6957.686904089328, 4199.958000419987, 4703.612434331881, 4801.663883528155, 4818.397700287464, 4823.397075365579, 6315.8736825263695, 6821.0, 6995.755148569776, 7093.787186384417, 7108.395786358165, 4357.302831546937, 4872.3909511311085, 4878.487758785347, 4880.0, 4880.829170978998, 3758.962410375861, 4789.137955168034, 4798.424189097331, 4978.5768209701855, 4990.6257030722845, 4406.096750166208, 5344.893102137936, 5502.58730595199, 5650.4349565043985, 5687.601867869293, 6306.432421082125, 6788.830279243059, 6880.827979300458, 6923.788337041098, 6935.029095926574, 1703.4719237036559, 1704.8295170482852, 1709.1881356355632, 1709.2991873332123, 1715.1595718098042, 1022.4580972084807, 1115.7489564847845, 1133.9603113890973, 1159.6173262823247, 1161.616666500053, 6480.546361754727, 6483.124778154983, 6540.869182616321, 6544.292100596831, 6558.704858281389, 6105.0, 6244.875102497925, 6894.931050689529, 7085.53943993649, 7189.388901943289, 6445.230529408204, 6465.741370345228, 6469.838254043687, 6476.222853257639, 6481.675916204171, 2774.902878399246, 3482.3534114073486, 3677.0072080538107, 3695.5195824543175, 3726.366517691999, 6152.446279834837, 6397.840053998687, 7268.745593904188, 7298.452616053711, 7355.190928997806, 7318.451116166309, 7320.560766354036, 7322.96338518304, 7331.9266807332315, 7355.190928997806, 7221.566705997657, 7261.824450940625, 7275.090613673291, 7294.1035330814375, 7294.379977701955, 6753.15585551806, 7075.646217689198, 7089.136420650861, 7103.467239957127, 7116.43068554504, 5308.849915784113, 5533.695646739468, 6949.235584085847, 7050.788476345718, 7056.329648683367, 5448.863778405572, 6837.829054273683, 6893.620850853251, 7202.603856787718, 7279.599622020628, 3736.1967177057063, 4504.211762941538, 4811.326414302071, 4867.95132048675, 4872.0, 6542.934570654233, 6654.733810647714, 6971.198312194163, 7075.780519863347, 7169.996200532034, 5749.856253593526, 6910.378065974087, 6933.7226510939, 6941.93058069433, 6948.096747422803, 6023.849403764941, 6487.9026814598765, 6862.965685171542, 6905.965470172617, 6910.896336555056, 5574.885022995336, 5744.913826292525, 6775.898361524482, 6785.6946437411425, 6853.897191542231, 5869.266341707286, 6171.722272497838, 6357.615894039735, 6817.693203805939, 6882.5870447773295, 6256.779927914013, 6680.565763225569, 6717.328267173439, 6742.831429214309, 6758.966205168943], "value": 5022.241302007634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.3078383374136, 817.3420396580754, 832.7793134819274, 840.5377042626558, 855.0167307596265, 14633.853661463381, 15126.941114122015, 16827.41104061358, 17350.826491735093, 17413.390531331406, 17578.736318955227, 18023.008734519597, 18077.728834067468, 18324.725129123075, 18355.0, 970.2335155227362, 989.2481713897417, 994.7612572982468, 1007.8236308645994, 1013.3362647465922, 677.4918810891832, 1020.4132623741341, 1022.4785359466651, 1022.8772547294335, 1023.912967397772, 737.7934178430048, 997.8852431970307, 1044.5978298355112, 1045.4877110215978, 1053.5785685725732, 1031.2008193649945, 1042.5621239079605, 1045.7856139491387, 1047.1622701838537, 1048.3709774135457, 771.3058247577226, 773.8684423647937, 774.6978678315461, 775.9922400775977, 776.6699152860068, 14147.646308842259, 14239.572812815528, 16911.83088169115, 17168.914155429142, 17182.484525464257, 17290.740638890427, 17305.221265042976, 17428.47714568565, 17830.37593684228, 17929.7310540342, 14426.062305950185, 14791.334389952477, 14856.480023199245, 14928.925355373154, 14932.0, 3452.9309413811584, 3472.670096340869, 3496.702780263681, 3508.017755028596, 3511.227529943434, 137710.0, 139713.3014334932, 139994.0, 140404.08585699453, 143040.0, 12273.447694853574, 12350.938245308716, 12508.249505029728, 12536.435860386122, 12574.81137782934, 21166.576668466852, 21648.458788530414, 21833.235836745636, 22138.88930555369, 22169.778302217095, 5570.164475328652, 6159.538034647419, 7438.6280685965485, 7688.692452301957, 7767.145613982458, 3913.158670885716, 3982.2234664240773, 4029.5366032906004, 4035.6771458283856, 4036.8990775230855, 4484.686071974996, 4538.410006699108, 4557.794899229542, 4600.217962946307, 4604.700694454849, 942.2603256443659, 949.6960972488737, 952.9475878826731, 954.355809828377, 955.2310390136007, 651.2705769538053, 657.4937298280297, 661.6757788683607, 669.8291935556402, 680.1973671068187, 11277.94361028206, 11869.643910682693, 12234.571789987307, 12339.75320493603, 12513.436895339732, 595.6872641863057, 618.8607563298312, 654.3881470824793, 673.4578664175386, 696.8571442854203, 5258.342707161605, 5654.8848272533305, 5683.318001839806, 5903.527717782567, 6046.18376519173, 98337.50831245798, 124942.3752881248, 134971.32514337366, 138127.0, 138281.23437531193, 77761.0, 100472.0, 101862.0, 103977.0, 106998.0, 94919.52540237393, 95833.52083239678, 96811.0, 99476.5026174879, 103151.45272820914, 11049.17131215146, 11186.888131118585, 11982.520699172284, 12138.696532586757, 12652.240865548096, 39853.40219896646, 45692.77153614211, 46451.53548464472, 47205.29192062191, 47385.763071184425, 79685.60157199176, 79687.0, 82181.02638886172, 83685.16314836773, 89218.0, 14878.85121148818, 17388.565285867953, 17396.39112631052, 17598.29606815713, 18304.81695183031, 13626.93186534061, 17472.03903785304, 18686.906565467085, 19286.8071319285, 19621.215151393786, 17150.571235719206, 17308.0, 18411.079446027914, 18543.72184417208, 18691.252349906394, 17215.7417638738, 18169.36407225741, 18191.18139683691, 18297.908510457364, 18598.628027439645, 17273.740893886854, 17726.113694314976, 17873.463796086136, 17894.642107158044, 17982.640347193246, 9664.468454235084, 12043.518259269531, 12212.0, 12303.876961230273, 12603.810942835682, 13292.534761283308, 14156.009079364345, 14344.686269048561, 14410.855891440951, 14515.637109072357], "value": 8662.755617489156, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 150.0, 98.0, 116.0, 164.0, 148.0, 116.0, 116.0, 119.0, 119.0], "value": 126.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 113.0, 136.0, 154.0, 116.0, 108.0, 136.0, 134.0, 204.0, 148.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 201.0, 209.0, 211.0, 240.0, 277.0, 250.0, 196.0, 196.0], "value": 218.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 85.0, 77.0, 145.0, 59.0, 57.0, 56.0, 67.0, 125.0, 59.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 112.0, 71.0, 72.0, 75.0, 143.0, 105.0, 105.0, 120.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 88.0, 88.0, 88.0, 100.0, 89.0, 89.0, 90.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 98.0, 96.0, 96.0, 96.0, 97.0, 102.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 92.0, 90.0, 93.0, 90.0, 89.0, 94.0, 126.0, 130.0], "value": 98.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 53.0, 52.0, 54.0, 53.0, 55.0, 53.0, 54.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 57.0, 63.0, 57.0, 60.0, 55.0, 57.0, 56.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 83.0, 84.0, 86.0, 83.0, 88.0, 91.0, 112.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 134.0, 166.0, 146.0, 137.0, 148.0, 139.0, 144.0, 157.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 180.0, 185.0, 225.0, 217.0, 181.0, 181.0, 188.0, 181.0, 177.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 66.0, 68.0, 69.0, 63.0, 66.0, 65.0, 65.0], "value": 66.6, "unit": "score"}]}]} 03:47:11 INFO - Return code: 0 03:47:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:47:12 INFO - # TBPL SUCCESS # 03:47:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:47:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:47:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:47:12 INFO - 2017-06-10 03:47:12.053 screenresolution[1698:11724] starting screenresolution argv=screenresolution get 03:47:12 INFO - 2017-06-10 03:47:12.056 screenresolution[1698:11724] Display 0: 1600x1200x32@60 03:47:12 INFO - 2017-06-10 03:47:12.063 screenresolution[1699:11728] starting screenresolution argv=screenresolution list 03:47:12 INFO - Available Modes on Display 0 03:47:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:47:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:47:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:47:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:47:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:47:12 INFO - Intel Iris: 03:47:12 INFO - Chipset Model: Intel Iris 03:47:12 INFO - Type: GPU 03:47:12 INFO - Bus: Built-In 03:47:12 INFO - VRAM (Dynamic, Max): 1536 MB 03:47:12 INFO - Vendor: Intel (0x8086) 03:47:12 INFO - Device ID: 0x0a2e 03:47:12 INFO - Revision ID: 0x0009 03:47:12 INFO - Displays: 03:47:12 INFO - Display: 03:47:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:47:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:47:12 INFO - Display Serial Number: 1600x1200 60 03:47:12 INFO - Main Display: Yes 03:47:12 INFO - Mirror: Off 03:47:12 INFO - Online: Yes 03:47:12 INFO - Rotation: Supported 03:47:12 INFO - Return code: 0 03:47:12 INFO - Running post-action listener: _resource_record_post_action 03:47:12 INFO - [mozharness: 2017-06-10 10:47:12.298727Z] Finished run-tests step (success) 03:47:12 INFO - Running post-run listener: _resource_record_post_run 03:47:12 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 32822272; Write bytes: 576322560; Read time: 336; Write time: 1870 03:47:12 INFO - TinderboxPrint: CPU usage
53.3% 03:47:12 INFO - TinderboxPrint: I/O read bytes / time
32,822,272 / 336 03:47:12 INFO - TinderboxPrint: I/O write bytes / time
576,322,560 / 1,870 03:47:12 INFO - TinderboxPrint: CPU idle
2,348.2 (46.7%) 03:47:12 INFO - TinderboxPrint: CPU system
1,020.5 (20.3%) 03:47:12 INFO - TinderboxPrint: CPU user
1,657.0 (33.0%) 03:47:12 INFO - TinderboxPrint: Swap in / out
281,145,344 / 0 03:47:12 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252989952; Write bytes: 253376512; Read time: 18079; Write time: 513 03:47:12 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:47:12 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 30360576; Write bytes: 318895104; Read time: 308; Write time: 1335 03:47:12 INFO - Running post-run listener: _upload_blobber_files 03:47:12 INFO - Blob upload gear active. 03:47:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:47:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:47:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:47:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:47:13 INFO - (blobuploader) - INFO - Open directory for files ... 03:47:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:47:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:47:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:14 INFO - (blobuploader) - INFO - Done attempting. 03:47:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:47:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:47:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:15 INFO - (blobuploader) - INFO - Done attempting. 03:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:17 INFO - (blobuploader) - INFO - Done attempting. 03:47:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:47:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:47:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:19 INFO - (blobuploader) - INFO - Done attempting. 03:47:19 INFO - (blobuploader) - INFO - Iteration through files over. 03:47:19 INFO - Return code: 0 03:47:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:47:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:47:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc7efdc7997cf7ed664c0f10629dd6e61207b0b3019462d6ef2cfee19030ffc061a76b26f54e49548f76f29b7cbec94b081914c7f060fc067c9101068c24e159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee5f6ee838af529e324c0d09a1851a226611884bc922858880834b33842046c23f1d5feb58305a12ec98bc9450b27b029fdeb6b6044f63ed94b723f819d042f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f14e1022b64a0c106c36ef970d6c4858e11d78e90addab3c2867feb7cfa013efb66c5c1d07bb12a0c1bdff19dded315e9f156669c3df9f3a822ddd12bcf36fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5552ed28db36688dc0e63ccf74ea72cfd6e2e4dab56cfbbaca5d8a652377c5634247063fd1e6c247158d57a4d542f4f1874dabcebd5f3d9528b556ca6615571e"} 03:47:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:47:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:47:19 INFO - Contents: 03:47:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc7efdc7997cf7ed664c0f10629dd6e61207b0b3019462d6ef2cfee19030ffc061a76b26f54e49548f76f29b7cbec94b081914c7f060fc067c9101068c24e159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee5f6ee838af529e324c0d09a1851a226611884bc922858880834b33842046c23f1d5feb58305a12ec98bc9450b27b029fdeb6b6044f63ed94b723f819d042f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f14e1022b64a0c106c36ef970d6c4858e11d78e90addab3c2867feb7cfa013efb66c5c1d07bb12a0c1bdff19dded315e9f156669c3df9f3a822ddd12bcf36fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5552ed28db36688dc0e63ccf74ea72cfd6e2e4dab56cfbbaca5d8a652377c5634247063fd1e6c247158d57a4d542f4f1874dabcebd5f3d9528b556ca6615571e"} 03:47:19 INFO - Running post-run listener: copy_logs_to_upload_dir 03:47:19 INFO - Copying logs to upload dir... 03:47:19 INFO - mkdir: /builds/slave/test/build/upload/logs 03:47:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.808348 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-10 03:47:19.291862) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 03:47:19.295813) ========= 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.5rvmZmOz85/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.6IdeeYcGE3/Listeners TMPDIR=/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/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/fc7efdc7997cf7ed664c0f10629dd6e61207b0b3019462d6ef2cfee19030ffc061a76b26f54e49548f76f29b7cbec94b081914c7f060fc067c9101068c24e159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee5f6ee838af529e324c0d09a1851a226611884bc922858880834b33842046c23f1d5feb58305a12ec98bc9450b27b029fdeb6b6044f63ed94b723f819d042f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f14e1022b64a0c106c36ef970d6c4858e11d78e90addab3c2867feb7cfa013efb66c5c1d07bb12a0c1bdff19dded315e9f156669c3df9f3a822ddd12bcf36fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5552ed28db36688dc0e63ccf74ea72cfd6e2e4dab56cfbbaca5d8a652377c5634247063fd1e6c247158d57a4d542f4f1874dabcebd5f3d9528b556ca6615571e"} build_url:https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/OrGR1vOGT2ijWd8MnLUm2g/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/fc7efdc7997cf7ed664c0f10629dd6e61207b0b3019462d6ef2cfee19030ffc061a76b26f54e49548f76f29b7cbec94b081914c7f060fc067c9101068c24e159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ee5f6ee838af529e324c0d09a1851a226611884bc922858880834b33842046c23f1d5feb58305a12ec98bc9450b27b029fdeb6b6044f63ed94b723f819d042f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f14e1022b64a0c106c36ef970d6c4858e11d78e90addab3c2867feb7cfa013efb66c5c1d07bb12a0c1bdff19dded315e9f156669c3df9f3a822ddd12bcf36fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5552ed28db36688dc0e63ccf74ea72cfd6e2e4dab56cfbbaca5d8a652377c5634247063fd1e6c247158d57a4d542f4f1874dabcebd5f3d9528b556ca6615571e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 03:47:19.332447) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:47:19.332830) ========= 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.5rvmZmOz85/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.6IdeeYcGE3/Listeners TMPDIR=/var/folders/2p/ylckvcmd7t13nzwklw7xtqch00000w/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.005252 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:47:19.385496) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 03:47:19.385873) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 03:47:19.386287) ========= ========= Total master_lag: 0.21 =========