builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0291 starttime: 1497271611.8 results: success (0) revision: f9605772a0c9098ed1bcaa98089b2c944ed69e9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:51.799883) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:51.800499) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:51.800813) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:51.887101) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:51.887457) ========= 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.zEvPcylVaX/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.EHySayR67k/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-12 05:46:51-- 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% 19.0M=0.001s 2017-06-12 05:46:52 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.517723 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:52.429523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:52.429853) ========= 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.zEvPcylVaX/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.EHySayR67k/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.043744 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:52.493862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 05:46:52.494212) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f9605772a0c9098ed1bcaa98089b2c944ed69e9b --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 f9605772a0c9098ed1bcaa98089b2c944ed69e9b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zEvPcylVaX/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.EHySayR67k/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-12 05:46:52,594 truncating revision to first 12 chars 2017-06-12 05:46:52,594 Setting DEBUG logging. 2017-06-12 05:46:52,594 attempt 1/10 2017-06-12 05:46:52,594 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f9605772a0c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 05:46:53,411 unpacking tar archive at: mozilla-central-f9605772a0c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.088787 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 05:46:53.605191) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:53.605502) ========= script_repo_revision: f9605772a0c9098ed1bcaa98089b2c944ed69e9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:53.605892) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:53.606305) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 05:46:53.625301) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-12 05:46:53.625711) ========= /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.zEvPcylVaX/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.EHySayR67k/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:46:53 INFO - MultiFileLogger online at 20170612 05:46:53 in /builds/slave/test 05:46:53 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 05:46:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:46:53 INFO - {'append_to_log': False, 05:46:53 INFO - 'base_work_dir': '/builds/slave/test', 05:46:53 INFO - 'blob_upload_branch': 'Firefox', 05:46:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:46:53 INFO - 'branch': 'Firefox', 05:46:53 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:53 INFO - 'config_files': ('talos/mac_config.py',), 05:46:53 INFO - 'default_actions': ('clobber', 05:46:53 INFO - 'read-buildbot-config', 05:46:53 INFO - 'download-and-extract', 05:46:53 INFO - 'populate-webroot', 05:46:53 INFO - 'create-virtualenv', 05:46:53 INFO - 'install', 05:46:53 INFO - 'setup-mitmproxy', 05:46:53 INFO - 'run-tests'), 05:46:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:53 INFO - 'download_minidump_stackwalk': True, 05:46:53 INFO - 'download_symbols': 'ondemand', 05:46:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:53 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:53 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:53 INFO - 'gecko_profile': False, 05:46:53 INFO - 'gecko_profile_interval': 0, 05:46:53 INFO - 'installer_path': 'installer.exe', 05:46:53 INFO - 'log_level': 'info', 05:46:53 INFO - 'log_name': 'talos', 05:46:53 INFO - 'log_to_console': True, 05:46:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:46:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:46:53 INFO - 'opt_config_files': (), 05:46:53 INFO - 'pip_index': False, 05:46:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:46:53 INFO - 'cmd': ('bash', 05:46:53 INFO - '-c', 05:46:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:46:53 INFO - 'enabled': True, 05:46:53 INFO - 'halt_on_failure': False, 05:46:53 INFO - 'name': 'check_screen_resolution'},), 05:46:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:46:53 INFO - 'cmd': ('bash', 05:46:53 INFO - '-c', 05:46:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:46:53 INFO - 'enabled': True, 05:46:53 INFO - 'halt_on_failure': False, 05:46:53 INFO - 'name': 'check_screen_resolution'},), 05:46:53 INFO - 'run_cmd_checks_enabled': True, 05:46:53 INFO - 'suite': 'dromaeojs-e10s', 05:46:53 INFO - 'system_bits': '32', 05:46:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:53 INFO - 'title': 't-yosemite-r7-0291', 05:46:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:53 INFO - 'use_talos_json': True, 05:46:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:46:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:53 INFO - 'work_dir': 'build'} 05:46:53 INFO - [mozharness: 2017-06-12 12:46:53.837069Z] Running clobber step. 05:46:53 INFO - Running pre-action listener: _resource_record_pre_action 05:46:53 INFO - Running main action method: clobber 05:46:53 INFO - rmtree: /builds/slave/test/build 05:46:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:46:56 INFO - Running post-action listener: _resource_record_post_action 05:46:56 INFO - [mozharness: 2017-06-12 12:46:56.354434Z] Finished clobber step (success) 05:46:56 INFO - [mozharness: 2017-06-12 12:46:56.354571Z] Running read-buildbot-config step. 05:46:56 INFO - Running pre-action listener: _resource_record_pre_action 05:46:56 INFO - Running main action method: read_buildbot_config 05:46:56 INFO - Using buildbot properties: 05:46:56 INFO - { 05:46:56 INFO - "project": "", 05:46:56 INFO - "product": "firefox", 05:46:56 INFO - "who": "cbook@mozilla.com", 05:46:56 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 05:46:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:46:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:46:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:46:56 INFO - "stage_platform": "macosx64", 05:46:56 INFO - "basedir": "/builds/slave/test", 05:46:56 INFO - "buildnumber": 92, 05:46:56 INFO - "platform": "yosemite_r7", 05:46:56 INFO - "parent_task_id": "TiYwUT4kTRS-f5Ali8Jcgw", 05:46:56 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:46:56 INFO - "slavebuilddir": "test", 05:46:56 INFO - "taskId": "W0Rc9hivQ6SSlIjaN2Bqow", 05:46:56 INFO - "branch": "mozilla-central", 05:46:56 INFO - "script_repo_revision": "production", 05:46:56 INFO - "revision": "f9605772a0c9098ed1bcaa98089b2c944ed69e9b", 05:46:56 INFO - "slavename": "t-yosemite-r7-0291", 05:46:56 INFO - "repo_path": "mozilla-central" 05:46:56 INFO - } 05:46:56 INFO - Finding installer, test and symbols from parent task. 05:46:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0Rc9hivQ6SSlIjaN2Bqow'}, attempt #1 05:46:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0Rc9hivQ6SSlIjaN2Bqow'}, attempt #1 05:46:57 INFO - Task dependencies: QmQzh6J-SgyadCP5z6g0FQ TiYwUT4kTRS-f5Ali8Jcgw 05:46:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw'}, attempt #1 05:46:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:46:57 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/test_packages.json 05:46:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/target.crashreporter-symbols.zip 05:46:57 INFO - Running post-action listener: _resource_record_post_action 05:46:57 INFO - [mozharness: 2017-06-12 12:46:57.812511Z] Finished read-buildbot-config step (success) 05:46:57 INFO - [mozharness: 2017-06-12 12:46:57.812647Z] Running download-and-extract step. 05:46:57 INFO - Running pre-action listener: _resource_record_pre_action 05:46:57 INFO - Running main action method: download_and_extract 05:46:57 INFO - mkdir: /builds/slave/test/build/tests 05:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:57 INFO - trying https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:46:57 INFO - Downloading https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/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 05:46:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/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 05:46:58 INFO - Downloaded 1328 bytes. 05:46:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:46:58 INFO - Using the following test package requirements: 05:46:58 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:46:58 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:46:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:46:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:46:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'jsshell-mac.zip'], 05:46:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:46:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:46:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:46:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:46:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 05:46:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:46:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:46:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:46:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:46:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:46:58 INFO - Fetch https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:47:00 INFO - Content-Length response header: 59540298 05:47:00 INFO - Bytes received: 59540298 05:47:18 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:47:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:47:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:18 INFO - Fetch https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:47:20 INFO - Content-Length response header: 59540298 05:47:20 INFO - Bytes received: 59540298 05:47:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:47:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:47:37 INFO - Fetch https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:47:38 INFO - Content-Length response header: 14450010 05:47:38 INFO - Bytes received: 14450010 05:47:38 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')]} 05:47:38 INFO - trying https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:47:38 INFO - Downloading https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:47:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/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 05:47:40 INFO - Downloaded 69311700 bytes. 05:47:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:47:40 INFO - mkdir: /builds/slave/test/properties 05:47:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:47:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:47:40 INFO - Contents: 05:47:40 INFO - build_url:https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:47:40 INFO - Running post-action listener: _resource_record_post_action 05:47:40 INFO - Running post-action listener: set_extra_try_arguments 05:47:40 INFO - [mozharness: 2017-06-12 12:47:40.441844Z] Finished download-and-extract step (success) 05:47:40 INFO - [mozharness: 2017-06-12 12:47:40.441986Z] Running populate-webroot step. 05:47:40 INFO - Running pre-action listener: _resource_record_pre_action 05:47:40 INFO - Running main action method: populate_webroot 05:47:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:47:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:47:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:47:40 INFO - '--tppagecycles', 05:47:40 INFO - '7'], 05:47:40 INFO - 'tests': ['tsvgm']}}, 05:47:40 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:47:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:47:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:47:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['damp', 'tps']}, 05:47:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'tests': ['damp', 'tps']}, 05:47:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['dromaeo_dom']}, 05:47:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:47:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:47:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:47:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:47:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['a11yr', 05:47:40 INFO - 'ts_paint', 05:47:40 INFO - 'tpaint', 05:47:40 INFO - 'sessionrestore', 05:47:40 INFO - 'sessionrestore_no_auto_restore', 05:47:40 INFO - 'tabpaint']}, 05:47:40 INFO - 'other-e10s': {'tests': ['a11yr', 05:47:40 INFO - 'ts_paint', 05:47:40 INFO - 'tpaint', 05:47:40 INFO - 'sessionrestore', 05:47:40 INFO - 'sessionrestore_no_auto_restore', 05:47:40 INFO - 'tabpaint']}, 05:47:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:47:40 INFO - 'ts_paint', 05:47:40 INFO - 'tpaint', 05:47:40 INFO - 'sessionrestore', 05:47:40 INFO - 'sessionrestore_no_auto_restore', 05:47:40 INFO - 'tabpaint']}, 05:47:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:47:40 INFO - 'ts_paint', 05:47:40 INFO - 'tpaint', 05:47:40 INFO - 'sessionrestore', 05:47:40 INFO - 'sessionrestore_no_auto_restore', 05:47:40 INFO - 'tabpaint']}, 05:47:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['a11yr', 05:47:40 INFO - 'ts_paint', 05:47:40 INFO - 'tpaint', 05:47:40 INFO - 'sessionrestore', 05:47:40 INFO - 'sessionrestore_no_auto_restore', 05:47:40 INFO - 'tabpaint']}, 05:47:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['a11yr', 05:47:40 INFO - 'ts_paint', 05:47:40 INFO - 'tpaint', 05:47:40 INFO - 'sessionrestore', 05:47:40 INFO - 'sessionrestore_no_auto_restore', 05:47:40 INFO - 'tabpaint']}, 05:47:40 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:47:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:47:40 INFO - 'bloom_basic_ref']}, 05:47:40 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:47:40 INFO - 'talos_options': ['--mitmproxy', 05:47:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:47:40 INFO - '--firstNonBlankPaint'], 05:47:40 INFO - 'tests': ['quantum_pageload_google', 05:47:40 INFO - 'quantum_pageload_youtube', 05:47:40 INFO - 'quantum_pageload_amazon', 05:47:40 INFO - 'quantum_pageload_facebook']}, 05:47:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['tsvgx', 05:47:40 INFO - 'tsvgr_opacity', 05:47:40 INFO - 'tart', 05:47:40 INFO - 'tscrollx', 05:47:40 INFO - 'cart', 05:47:40 INFO - 'tsvg_static']}, 05:47:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:47:40 INFO - 'tsvgr_opacity', 05:47:40 INFO - 'tart', 05:47:40 INFO - 'tscrollx', 05:47:40 INFO - 'cart', 05:47:40 INFO - 'tsvg_static']}, 05:47:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'talos_options': ['--disable-e10s'], 05:47:40 INFO - 'tests': ['tp5o']}, 05:47:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:47:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'talos_options': ['--disable-e10s', 05:47:40 INFO - '--xperf_path', 05:47:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:40 INFO - 'tests': ['tp5n']}, 05:47:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:47:40 INFO - 'talos_options': ['--xperf_path', 05:47:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:40 INFO - 'tests': ['tp5n']}}, 05:47:40 INFO - 'talos.zip': {'path': '', 05:47:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:47:40 INFO - Running post-action listener: _resource_record_post_action 05:47:40 INFO - [mozharness: 2017-06-12 12:47:40.464183Z] Finished populate-webroot step (success) 05:47:40 INFO - [mozharness: 2017-06-12 12:47:40.464335Z] Running create-virtualenv step. 05:47:40 INFO - Running pre-action listener: _resource_record_pre_action 05:47:40 INFO - Running main action method: create_virtualenv 05:47:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:47:40 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 05:47:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:47:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:47:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:47:40 INFO - Using real prefix '/tools/python27' 05:47:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:47:41 INFO - Installing distribute.............................................................................................................................................................................................done. 05:47:44 INFO - Installing pip.................done. 05:47:44 INFO - Return code: 0 05:47:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:47:44 INFO - Reading from file tmpfile_stdout 05:47:44 INFO - Output received: 05:47:44 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) 05:47:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:47:44 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')]} 05:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:44 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')]} 05:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:44 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:47:44 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 05:47:44 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 05:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:44 INFO - 'HOME': '/Users/cltbld', 05:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:44 INFO - 'LOGNAME': 'cltbld', 05:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:44 INFO - 'MOZ_NO_REMOTE': '1', 05:47:44 INFO - 'NO_EM_RESTART': '1', 05:47:44 INFO - 'PAGER': '/bin/cat', 05:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:44 INFO - 'PWD': '/builds/slave/test', 05:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:44 INFO - 'SHELL': '/bin/bash', 05:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:47:44 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:44 INFO - 'USER': 'cltbld', 05:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:44 INFO - 'XPC_FLAGS': '0x0', 05:47:44 INFO - 'XPC_SERVICE_NAME': '0', 05:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:44 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 05:47:44 INFO - Cleaning up... 05:47:44 INFO - Return code: 0 05:47:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:47:44 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')]} 05:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:44 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')]} 05:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:44 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:47:44 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 05:47:44 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 05:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:44 INFO - 'HOME': '/Users/cltbld', 05:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:44 INFO - 'LOGNAME': 'cltbld', 05:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:44 INFO - 'MOZ_NO_REMOTE': '1', 05:47:44 INFO - 'NO_EM_RESTART': '1', 05:47:44 INFO - 'PAGER': '/bin/cat', 05:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:44 INFO - 'PWD': '/builds/slave/test', 05:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:44 INFO - 'SHELL': '/bin/bash', 05:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:47:44 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:44 INFO - 'USER': 'cltbld', 05:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:44 INFO - 'XPC_FLAGS': '0x0', 05:47:44 INFO - 'XPC_SERVICE_NAME': '0', 05:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:44 INFO - Downloading/unpacking psutil>=3.1.1 05:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:47:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:47 INFO - Installing collected packages: psutil 05:47:47 INFO - Running setup.py install for psutil 05:47:47 INFO - building 'psutil._psutil_osx' extension 05:47:47 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 05:47:47 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 05:47:47 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 05:47:48 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 05:47:48 INFO - building 'psutil._psutil_posix' extension 05:47:48 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 05:47:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:47:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:47:48 INFO - ^ 05:47:48 INFO - 1 warning generated. 05:47:48 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 05:47:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:48 INFO - Successfully installed psutil 05:47:48 INFO - Cleaning up... 05:47:48 INFO - Return code: 0 05:47:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:48 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:47:48 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 05:47:48 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 05:47:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:47:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:48 INFO - 'HOME': '/Users/cltbld', 05:47:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:48 INFO - 'LOGNAME': 'cltbld', 05:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:48 INFO - 'MOZ_NO_REMOTE': '1', 05:47:48 INFO - 'NO_EM_RESTART': '1', 05:47:48 INFO - 'PAGER': '/bin/cat', 05:47:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:48 INFO - 'PWD': '/builds/slave/test', 05:47:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:48 INFO - 'SHELL': '/bin/bash', 05:47:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:47:48 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:47:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:48 INFO - 'USER': 'cltbld', 05:47:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:48 INFO - 'XPC_FLAGS': '0x0', 05:47:48 INFO - 'XPC_SERVICE_NAME': '0', 05:47:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:47:50 INFO - Installing collected packages: mozsystemmonitor 05:47:50 INFO - Running setup.py install for mozsystemmonitor 05:47:50 INFO - Successfully installed mozsystemmonitor 05:47:50 INFO - Cleaning up... 05:47:50 INFO - Return code: 0 05:47:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:47:50 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')]} 05:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:50 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')]} 05:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:50 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:47:50 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 05:47:50 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 05:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:50 INFO - 'HOME': '/Users/cltbld', 05:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:50 INFO - 'LOGNAME': 'cltbld', 05:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:50 INFO - 'MOZ_NO_REMOTE': '1', 05:47:50 INFO - 'NO_EM_RESTART': '1', 05:47:50 INFO - 'PAGER': '/bin/cat', 05:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:50 INFO - 'PWD': '/builds/slave/test', 05:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:50 INFO - 'SHELL': '/bin/bash', 05:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:47:50 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:50 INFO - 'USER': 'cltbld', 05:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:50 INFO - 'XPC_FLAGS': '0x0', 05:47:50 INFO - 'XPC_SERVICE_NAME': '0', 05:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:50 INFO - Downloading/unpacking jsonschema==2.5.1 05:47:50 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 05:47:50 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 05:47:50 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 05:47:50 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 05:47:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:47:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:47:51 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 05:47:51 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 05:47:51 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 05:47:51 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 05:47:52 INFO - Downloading functools32-3.2.3-2.tar.gz 05:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:47:52 INFO - warning: no files found matching '*.txt' 05:47:52 INFO - no previously-included directories found matching 'build' 05:47:52 INFO - no previously-included directories found matching 'dist' 05:47:52 INFO - no previously-included directories found matching '.git*' 05:47:52 INFO - Installing collected packages: jsonschema, functools32 05:47:52 INFO - Running setup.py install for functools32 05:47:52 INFO - warning: no files found matching '*.txt' 05:47:52 INFO - no previously-included directories found matching 'build' 05:47:52 INFO - no previously-included directories found matching 'dist' 05:47:52 INFO - no previously-included directories found matching '.git*' 05:47:52 INFO - Successfully installed jsonschema functools32 05:47:52 INFO - Cleaning up... 05:47:52 INFO - Return code: 0 05:47:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47: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')]} 05:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47: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', '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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:47: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:47: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 functools32==3.2.3-2 05:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:52 INFO - 'HOME': '/Users/cltbld', 05:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:52 INFO - 'LOGNAME': 'cltbld', 05:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:52 INFO - 'MOZ_NO_REMOTE': '1', 05:47:52 INFO - 'NO_EM_RESTART': '1', 05:47:52 INFO - 'PAGER': '/bin/cat', 05:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:52 INFO - 'PWD': '/builds/slave/test', 05:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:52 INFO - 'SHELL': '/bin/bash', 05:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:47:52 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:52 INFO - 'USER': 'cltbld', 05:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:52 INFO - 'XPC_FLAGS': '0x0', 05:47:52 INFO - 'XPC_SERVICE_NAME': '0', 05:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:47:52 INFO - Cleaning up... 05:47:52 INFO - Return code: 0 05:47:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47: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')]} 05:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47: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', '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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:47: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:47: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 blobuploader==1.2.4 05:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:52 INFO - 'HOME': '/Users/cltbld', 05:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:52 INFO - 'LOGNAME': 'cltbld', 05:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:52 INFO - 'MOZ_NO_REMOTE': '1', 05:47:52 INFO - 'NO_EM_RESTART': '1', 05:47:52 INFO - 'PAGER': '/bin/cat', 05:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:52 INFO - 'PWD': '/builds/slave/test', 05:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:52 INFO - 'SHELL': '/bin/bash', 05:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:47:52 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:52 INFO - 'USER': 'cltbld', 05:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:52 INFO - 'XPC_FLAGS': '0x0', 05:47:52 INFO - 'XPC_SERVICE_NAME': '0', 05:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:53 INFO - Downloading/unpacking blobuploader==1.2.4 05:47:53 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 05:47:53 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 05:47:53 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 05:47:53 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 05:47:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:47:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:47:54 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 05:47:54 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 05:47:54 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 05:47:54 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 05:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:47:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:55 INFO - Downloading docopt-0.6.1.tar.gz 05:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:47:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:47:55 INFO - Running setup.py install for blobuploader 05:47:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:47:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:47:55 INFO - Running setup.py install for requests 05:47:56 INFO - Running setup.py install for docopt 05:47:56 INFO - Successfully installed blobuploader requests docopt 05:47:56 INFO - Cleaning up... 05:47:56 INFO - Return code: 0 05:47:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47: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')]} 05:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:56 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:47:56 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 05:47:56 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 05:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:56 INFO - 'HOME': '/Users/cltbld', 05:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:56 INFO - 'LOGNAME': 'cltbld', 05:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:56 INFO - 'MOZ_NO_REMOTE': '1', 05:47:56 INFO - 'NO_EM_RESTART': '1', 05:47:56 INFO - 'PAGER': '/bin/cat', 05:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:56 INFO - 'PWD': '/builds/slave/test', 05:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:56 INFO - 'SHELL': '/bin/bash', 05:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:47:56 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:56 INFO - 'USER': 'cltbld', 05:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:56 INFO - 'XPC_FLAGS': '0x0', 05:47:56 INFO - 'XPC_SERVICE_NAME': '0', 05:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-MWshKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-J1XAy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-2sxnJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:56 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-aukuJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ZIB1cY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-dDdEIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-6WYy5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-fNi3pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-cn7Smv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-I2GkiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Ov1WNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ZAxxPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:57 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-FjYu5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-aXAy2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-EKXxoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-xj9vNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:58 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-fTOcuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:47:58 INFO - Running setup.py install for manifestparser 05:47:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Running setup.py install for mozcrash 05:47:58 INFO - Running setup.py install for mozdebug 05:47:58 INFO - Running setup.py install for mozdevice 05:47:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for mozfile 05:47:59 INFO - Running setup.py install for mozhttpd 05:47:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for mozinfo 05:47:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for mozInstall 05:47:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for mozleak 05:47:59 INFO - Running setup.py install for mozlog 05:47:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for moznetwork 05:48:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:48:00 INFO - Running setup.py install for mozprocess 05:48:00 INFO - Running setup.py install for mozprofile 05:48:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:48:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:48:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:48:00 INFO - Running setup.py install for mozrunner 05:48:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:48:00 INFO - Running setup.py install for mozscreenshot 05:48:00 INFO - Running setup.py install for moztest 05:48:00 INFO - Running setup.py install for mozversion 05:48:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:48:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:48:01 INFO - Cleaning up... 05:48:01 INFO - Return code: 0 05:48:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:01 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:48:01 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 05:48:01 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 05:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:01 INFO - 'HOME': '/Users/cltbld', 05:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:01 INFO - 'LOGNAME': 'cltbld', 05:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:01 INFO - 'MOZ_NO_REMOTE': '1', 05:48:01 INFO - 'NO_EM_RESTART': '1', 05:48:01 INFO - 'PAGER': '/bin/cat', 05:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:01 INFO - 'PWD': '/builds/slave/test', 05:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:01 INFO - 'SHELL': '/bin/bash', 05:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:48:01 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:01 INFO - 'USER': 'cltbld', 05:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:01 INFO - 'XPC_FLAGS': '0x0', 05:48:01 INFO - 'XPC_SERVICE_NAME': '0', 05:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:01 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-AHyVaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:01 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)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:01 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-2jP8Gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:01 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)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:01 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-kFFmIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:01 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)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:01 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-enYdjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:01 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)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:01 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-iQfBUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:01 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)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:01 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-jtzDoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:01 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)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:01 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ttJr8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-s4rCec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-I2smTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-s6GKnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-PkHs1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-xI6VLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ENvKWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-k6ZRd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-nPNlO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:02 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)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:02 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Bmy2fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:03 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)) 05:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-vd3PRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:03 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)) 05:48:03 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)) 05:48:03 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)) 05:48:03 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)) 05:48:03 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)) 05:48:03 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)) 05:48:03 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)) 05:48:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:03 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 05:48:03 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 05:48:03 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 05:48:03 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 05:48:04 INFO - Downloading blessings-1.6.tar.gz 05:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:48:04 INFO - Installing collected packages: blessings 05:48:04 INFO - Running setup.py install for blessings 05:48:04 INFO - Successfully installed blessings 05:48:04 INFO - Cleaning up... 05:48:04 INFO - Return code: 0 05:48:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:48:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:05 INFO - Reading from file tmpfile_stdout 05:48:05 INFO - Current package versions: 05:48:05 INFO - blessings == 1.6 05:48:05 INFO - blobuploader == 1.2.4 05:48:05 INFO - docopt == 0.6.1 05:48:05 INFO - functools32 == 3.2.3-2 05:48:05 INFO - jsonschema == 2.5.1 05:48:05 INFO - manifestparser == 1.1 05:48:05 INFO - mozInstall == 1.13 05:48:05 INFO - mozcrash == 1.0 05:48:05 INFO - mozdebug == 0.1 05:48:05 INFO - mozdevice == 0.51 05:48:05 INFO - mozfile == 1.2 05:48:05 INFO - mozhttpd == 0.7 05:48:05 INFO - mozinfo == 0.10 05:48:05 INFO - mozleak == 0.1 05:48:05 INFO - mozlog == 3.4 05:48:05 INFO - moznetwork == 0.27 05:48:05 INFO - mozprocess == 0.25 05:48:05 INFO - mozprofile == 0.28 05:48:05 INFO - mozrunner == 6.13 05:48:05 INFO - mozscreenshot == 0.1 05:48:05 INFO - mozsystemmonitor == 0.3 05:48:05 INFO - moztest == 0.8 05:48:05 INFO - mozversion == 1.4 05:48:05 INFO - psutil == 3.1.1 05:48:05 INFO - requests == 1.2.3 05:48:05 INFO - wsgiref == 0.1.2 05:48:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:05 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:48:05 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 05:48:05 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 05:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:05 INFO - 'HOME': '/Users/cltbld', 05:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:05 INFO - 'LOGNAME': 'cltbld', 05:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:05 INFO - 'MOZ_NO_REMOTE': '1', 05:48:05 INFO - 'NO_EM_RESTART': '1', 05:48:05 INFO - 'PAGER': '/bin/cat', 05:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:05 INFO - 'PWD': '/builds/slave/test', 05:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:05 INFO - 'SHELL': '/bin/bash', 05:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:48:05 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:05 INFO - 'USER': 'cltbld', 05:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:05 INFO - 'XPC_FLAGS': '0x0', 05:48:05 INFO - 'XPC_SERVICE_NAME': '0', 05:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:05 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)) 05:48:05 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)) 05:48:05 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)) 05:48:05 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)) 05:48:05 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)) 05:48:05 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)) 05:48:05 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)) 05:48:05 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)) 05:48:05 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)) 05:48:05 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:06 INFO - Downloading marionette_driver-1.4.0.tar.gz 05:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 05:48:06 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)) 05:48:06 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)) 05:48:06 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)) 05:48:06 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)) 05:48:06 INFO - Installing collected packages: marionette-driver 05:48:06 INFO - Running setup.py install for marionette-driver 05:48:07 INFO - Successfully installed marionette-driver 05:48:07 INFO - Cleaning up... 05:48:07 INFO - Return code: 0 05:48:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:48:07 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')]} 05:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:07 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')]} 05:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:07 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 0x10bcf5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae05c8020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, '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.zEvPcylVaX/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.EHySayR67k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 05:48:07 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 05:48:07 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 05:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:07 INFO - 'HOME': '/Users/cltbld', 05:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:07 INFO - 'LOGNAME': 'cltbld', 05:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:07 INFO - 'MOZ_NO_REMOTE': '1', 05:48:07 INFO - 'NO_EM_RESTART': '1', 05:48:07 INFO - 'PAGER': '/bin/cat', 05:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:07 INFO - 'PWD': '/builds/slave/test', 05:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:07 INFO - 'SHELL': '/bin/bash', 05:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:48:07 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:07 INFO - 'USER': 'cltbld', 05:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:07 INFO - 'XPC_FLAGS': '0x0', 05:48:07 INFO - 'XPC_SERVICE_NAME': '0', 05:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:48:07 INFO - Cleaning up... 05:48:07 INFO - Return code: 0 05:48:07 INFO - Running post-action listener: _resource_record_post_action 05:48:07 INFO - Running post-action listener: _start_resource_monitoring 05:48:07 INFO - Starting resource monitoring. 05:48:07 INFO - [mozharness: 2017-06-12 12:48:07.459517Z] Finished create-virtualenv step (success) 05:48:07 INFO - [mozharness: 2017-06-12 12:48:07.460613Z] Running install step. 05:48:07 INFO - Running pre-action listener: _resource_record_pre_action 05:48:07 INFO - Running main action method: install 05:48:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:07 INFO - Reading from file tmpfile_stdout 05:48:07 INFO - Detecting whether we're running mozinstall >=1.0... 05:48:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:48:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:48:07 INFO - Reading from file tmpfile_stdout 05:48:07 INFO - Output received: 05:48:07 INFO - Usage: mozinstall [options] installer 05:48:07 INFO - Options: 05:48:07 INFO - -h, --help show this help message and exit 05:48:07 INFO - -d DEST, --destination=DEST 05:48:07 INFO - Directory to install application into. [default: 05:48:07 INFO - "/builds/slave/test"] 05:48:07 INFO - --app=APP Application being installed. [default: firefox] 05:48:07 INFO - mkdir: /builds/slave/test/build/application 05:48:07 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'] 05:48:07 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 05:48:29 INFO - Reading from file tmpfile_stdout 05:48:29 INFO - Output received: 05:48:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:48:29 INFO - Running post-action listener: _resource_record_post_action 05:48:29 INFO - [mozharness: 2017-06-12 12:48:29.785643Z] Finished install step (success) 05:48:29 INFO - [mozharness: 2017-06-12 12:48:29.785780Z] Running setup-mitmproxy step. 05:48:29 INFO - Running pre-action listener: _resource_record_pre_action 05:48:29 INFO - Running main action method: setup_mitmproxy 05:48:29 INFO - Skipping: mitmproxy is not required 05:48:29 INFO - Running post-action listener: _resource_record_post_action 05:48:29 INFO - [mozharness: 2017-06-12 12:48:29.786435Z] Finished setup-mitmproxy step (success) 05:48:29 INFO - [mozharness: 2017-06-12 12:48:29.786550Z] Running run-tests step. 05:48:29 INFO - Running pre-action listener: _resource_record_pre_action 05:48:29 INFO - Running main action method: run_tests 05:48:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:29 INFO - 2017-06-12 05:48:29.801 screenresolution[1638:9001] starting screenresolution argv=screenresolution get 05:48:29 INFO - 2017-06-12 05:48:29.804 screenresolution[1638:9001] Display 0: 1600x1200x32@60 05:48:29 INFO - 2017-06-12 05:48:29.809 screenresolution[1639:9004] starting screenresolution argv=screenresolution list 05:48:29 INFO - Available Modes on Display 0 05:48:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:30 INFO - Intel Iris: 05:48:30 INFO - Chipset Model: Intel Iris 05:48:30 INFO - Type: GPU 05:48:30 INFO - Bus: Built-In 05:48:30 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:30 INFO - Vendor: Intel (0x8086) 05:48:30 INFO - Device ID: 0x0a2e 05:48:30 INFO - Revision ID: 0x0009 05:48:30 INFO - Displays: 05:48:30 INFO - Display: 05:48:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:30 INFO - Display Serial Number: 1600x1200 60 05:48:30 INFO - Main Display: Yes 05:48:30 INFO - Mirror: Off 05:48:30 INFO - Online: Yes 05:48:30 INFO - Rotation: Supported 05:48:30 INFO - Return code: 0 05:48:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:48:30 INFO - Python 2.7.3 05:48:30 INFO - Return code: 0 05:48:30 INFO - grabbing minidump binary from tooltool 05:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:30 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 0x10ba4bd30>, '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 0x10ba15cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:48:30 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 05:48:30 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 05:48:30 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 05:48:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:48:30 INFO - Return code: 0 05:48:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:48:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:48:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:48:30 INFO - ENV: RUST_BACKTRACE is now 1 05:48:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:48:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:48:30 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/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 05:48:30 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/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0291 --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 05:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zEvPcylVaX/Render', 05:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:30 INFO - 'HOME': '/Users/cltbld', 05:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:30 INFO - 'LOGNAME': 'cltbld', 05:48:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:48:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:30 INFO - 'MOZ_NO_REMOTE': '1', 05:48:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:48:30 INFO - 'NO_EM_RESTART': '1', 05:48:30 INFO - 'PAGER': '/bin/cat', 05:48:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:30 INFO - 'PWD': '/builds/slave/test', 05:48:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:30 INFO - 'RUST_BACKTRACE': '1', 05:48:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:48:30 INFO - 'SHELL': '/bin/bash', 05:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EHySayR67k/Listeners', 05:48:30 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 05:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:30 INFO - 'USER': 'cltbld', 05:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:30 INFO - 'XPC_FLAGS': '0x0', 05:48:30 INFO - 'XPC_SERVICE_NAME': '0', 05:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:30 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/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 05:48:30 INFO - mozversion application_buildid: 20170612041950 05:48:30 INFO - mozversion application_changeset: f9605772a0c9098ed1bcaa98089b2c944ed69e9b 05:48:30 INFO - mozversion application_display_name: Nightly 05:48:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:48:30 INFO - mozversion application_name: Firefox 05:48:30 INFO - mozversion application_remotingname: firefox 05:48:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:48:30 INFO - mozversion application_vendor: Mozilla 05:48:30 INFO - mozversion application_version: 55.0a1 05:48:30 INFO - mozversion platform_buildid: 20170612041950 05:48:30 INFO - mozversion platform_changeset: f9605772a0c9098ed1bcaa98089b2c944ed69e9b 05:48:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:48:30 INFO - mozversion platform_version: 55.0a1 05:48:30 INFO - using testdate: 1497271710 05:48:30 INFO - actual date: 1497271710 05:48:30 INFO - starting webserver on 'localhost:49229' 05:48:30 INFO - SUITE-START | Running 2 tests 05:48:30 INFO - TEST-START | dromaeo_css 05:48:30 INFO - operating with platform_type : mac_ 05:48:30 INFO - Initialising browser for dromaeo_css test... 05:48:30 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpLJcYoP/profile 05:48:30 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 05:49:04 INFO - TEST-INFO | 1649: killed by SIGTERM 05:49:04 INFO - Browser initialized. 05:49:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:49:04 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpLJcYoP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:06 INFO - PID 1657 | 2017-06-12 05:49:06.207 plugin-container[1658:9677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 05:49:06 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:06 INFO - PID 1657 | 2017-06-12 05:49:06.207 plugin-container[1658:9677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 05:49:06 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:06 INFO - PID 1657 | 2017-06-12 05:49:06.207 plugin-container[1658:9677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:06 INFO - PID 1657 | 2017-06-12 05:49:06.969 plugin-container[1659:9725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 05:49:06 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:06 INFO - PID 1657 | 2017-06-12 05:49:06.969 plugin-container[1659:9725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 05:49:06 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:06 INFO - PID 1657 | 2017-06-12 05:49:06.969 plugin-container[1659:9725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:16 INFO - PID 1657 | RSS: Main: 253014016 05:49:16 INFO - PID 1657 | 05:52:18 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 05:52:20 INFO - PID 1657 | RSS: Main: 269131776 05:52:20 INFO - PID 1657 | 05:55:22 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 05:55:23 INFO - PID 1657 | RSS: Main: 278724608 05:55:23 INFO - PID 1657 | 05:58:24 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 05:58:25 INFO - PID 1657 | RSS: Main: 280342528 05:58:25 INFO - PID 1657 | 06:01:27 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 06:01:27 INFO - PID 1657 | RSS: Main: 280764416 06:01:27 INFO - PID 1657 | 06:04:30 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 06:04:31 INFO - PID 1657 | RSS: Main: 282566656 06:04:31 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 06:07:33 INFO - PID 1657 | RSS: Main: 283189248 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | __start_tp_report 06:07:33 INFO - PID 1657 | _x_x_mozilla_page_load 06:07:33 INFO - PID 1657 | _x_x_mozilla_page_load_details 06:07:33 INFO - PID 1657 | |i|pagename|runs| 06:07:33 INFO - PID 1657 | |0;dojo.html;3468.167639766457;3496.125968507873;3587.4618807178927;3590.2819436112786;3590.910227244319;6064.78773242936;6079.544034197436;6103.60326578772;6151.81544553663;6166.951618224902;6113.877722445548;6174.950258456062;6203.038529028008;6221.533384996121;6237.688115594214;4400.933985990197;5372;5372.892542149164;5372.91940620891;5498.065328894098;5404.32445944257;5455.781768729246;5478.342598888119;5498.230247765316;5504.2294078829;5305.522502974751;5313.44208858072;5341.092014357569;5422.728863556826;5423.701696406716;5368.543673787733;5435.076037073707;5435.402105768402;5457.481539253765;5475.479829416239;4378.978105109454;5358.705271210082;5394.02907476654;5406.83779486616;5463.180522921554;5062.848114556569;5087.313212716273;6115.602485838406;6219.720112594944;6231.937680623181;5749.022666146723;6386.201724784402;6611;6654.467642588581;6665.166854143232;6004.459598636144;6098.14625952367;6599.5380323377385;6661.100751398548;6793.524453288272;6269.905951410732;6297.4017468340435;6322.399372059647;6346.333634968354;6350.23797144341;6897.7585784497305;6910.965445172742;6923.446124310043;6933.7226510939;6935.3411425914455;6331.778387756406;6387.968060159762;6391.1371964785085;6413.935860641333;6568.638724870082;6578.703958321887;6590.110335104795;6689.331066893272;6702.597844129368;6711.63086030273;6612.107365505595;6613.206415230203;6619.238787539494;6636.535442518977;6677.833054173684;6381.553291269566;6461.25695545009;6626.668666566652;6634.933650663432;6642.535022548472;5279.551238144724;5318.361796584357;5332.093544097513;5347.037533243975;5363.034653762282;4722.6221902247735;4778.37881075458;4805.567498925114;4824.34871292378;4867.1969125093965;4673.953260467351;6139.956207444745;6326.715297811517;6334.588251763621;6343.619382837044;6714.597124172467;6767.593944363354;6777.762778302835;6797;6803.863922721518;6209;6260.467860231931;6305.653189074645;6313.2424109107205;6322.304546499881;5754.424557544212;5773.971130144322;5794.971025144931;5817.418258174233;5858.648481091148;6813.659317034128;6821.351971562793;6843.384095431535;6879.552829066095;6918.481113916376;6730.360615741497;6736.02327662496;6755.898661520179;6760.797176084725;6768.56004359715;6837.247902730596;6868.038474613458;6889.517733758789;6904.827379315558;6905.413039891666;6356.809295721136;6453.09656648079;6597.307282735435;6600.141981542369;6621.106150669598;6574.572652777554;6588.439982601532;6641.900371494528;6709.094272273181;6721.697523611547;5579.637323573955;6290.899092658858;6448.387403196689;6474.708638111202;6478.060681201106;6382.053473690383;6449.4840412765925;6452.419282264619;6458.580192287486;6461.57999730016;6228.813135605939;6239.532035097477;6307.274663413767;6321.209848768904;6339.144215530844;6375.6174629522375;6376.936230637634;6434.517411194086;6445.097686323825;6453.580517266362;5620.325560932714;6055.031195008777;6251.46862516673;6279.246490421178;6323.114763932961 06:07:33 INFO - PID 1657 | |1;ext.html;622.5797586629025;737.4248086492536;815.4129027100486;835.2190701693914;850.3452341696893;36506.634933650654;37345.81327093364;37570;37616;37756.99999999996;37006.25987480249;37349;37354.62645373545;37438.0640483988;37454.81272593633;6413.903791443132;7207.927920720778;7330.193678695339;7332.1934587195365;7336.963315183417;6332.651704156269;7117.679704413314;7369.336759691627;7404.1855395906405;7407.259274072603;7900.525968441912;8118.309943654796;8118.512889226666;8128.471649342803;8140.308073813733;8082.757517274491;8144.063432705197;8152.18478152186;8161.836763264731;8170.836583268331;7812.492188007761;7863.528188308663;7870.409719271078;7883.842323153504;7897.170797066283;34757;34762.478562821576;34772.65227347719;34791.4781278281;34911.825440872635;3504.912377190565;3516.9296614067575;3547.9822600886832;3550.289942011608;3550.3076900004507;29242.70757292421;29385.41229175426;29431.11706648804;29439.558406623917;29481.115566533037;3027.3188532579993;3035.741961933238;3069.048594935555;3081.3683194944983;3086.9691303086906;236434;240432;240739;304728;305748;46439.535604644196;47137.585872423195;47252.99999999931;47383.81540461516;47538.76230618825;49462.258066128314;49509.50490495121;49578.25632615513;49591.50408495941;49604.50395496071;13700.65748356299;13834.584962451143;13853.307334633228;13859.514916977858;13867;11115.666530003951;11129.666110016713;11225.989660930556;11248.775024499628;11250.381229032319;13442.529511467052;13452.865471345161;13503.459861605426;13508.662283442796;13570.86429135696;5353.71510837404;5656.434356564393;5920.822375328747;5952.226210592596;5983.611065280743;980.1775265692809;981.6613268422383;983.8179936711731;1042.5152304178594;1053.1101219469535;18790;21954.89022554909;22073.227437039626;22269.66595501069;22909.42726431819;1069;1105.5412004018403;1106.5131342209563;1111.6776134920783;1131.8585176852894;11174.44127793607;11242.618171036114;11527.365994870363;11871;12047.156699030984;307141;310722;313574;315270;316205;52982.205266921024;53109.67225819385;53183;53256.73371633117;53361;1458.1105525629357;1501.06183635228;1507.4045751927927;1511.8261399938929;1516.1812621184438;21620.13519459204;21804.127834887062;22047.57476677532;22230.888455576835;22341.77658223462;29790.702092978794;37637.623623764506;37788.05529861776;37820;37906.052348691504;265608;266578;266877;266895;267055;28507;29403.85298073496;29618.519074046642;34050.829745851115;34179;35777.82111089532;40306.38774448989;41343.58656413518;42000.369994449495;42102.36846447244;27166.320841979108;27823.721762782112;28134.577981329887;28210;28565.42869142647;28731.988760786546;28828.279293017844;30144.547831782103;30553.847230763702;30608.234794130323;33973.66026339804;36114.09714757152;36119.73580924655;36132.81933590315;36192.63807361998;20459;27958.021469248477;28954.42091158123;29002.984895528556;30159.69840301569;20619.690704639142;21718;23378.883105584362;23389.532209356057;23403 06:07:33 INFO - PID 1657 | |2;jquery.html;495.45747406589794;500.29458463566357;501.4058340866073;515.9716215608141;520.0924386944782;21025.68461473079;21734.89132554339;21752;22349;22557.774422255792;16831.74752378715;22974.425639359026;23208.419789505228;23589;23632.409189770264;6057.54568407369;6252.6561039142825;6255.218097737783;6268.467180289681;6269.090981807635;6134.877302453949;6250.593711408744;6251.437370636641;6254.781082662108;6261.092141639459;5466.043442397585;5769.653820770767;6165.445109940105;6187.876242475148;6219.098230756537;6042.456178943894;6145.846353841145;6149.938500615003;6186.133941248228;6195.0707393890825;5577.581681373913;5582.385937546866;5584.301962254718;5586.636868603528;5597.328320601513;26721.73278267212;28829.84680612773;29306.41387172266;29344.41311173786;29367.853160734274;2117.4388786971463;2194.692743015979;2216.900239489227;2219.966700499494;2230.275160572804;17861.821381786147;18348.26606935721;18368.081595920285;18487.445376638723;18600.813991860043;2139.3154190658997;2163.610550100981;2191.3425972208274;2195.769444208351;2200.2739096098408;158053;165392;166191;179783.9999999987;205903;13958.790618140736;14167.71664566703;14433.278336083154;14689.632759180893;14712.337944792296;19004.23983040663;19051;19055.80944190568;19063;19068.61862762737;9253.028432014677;9368.812623747486;9387.249020078378;9420.576074076684;9475.76310592227;8647;8650.653973840976;8653.004904436071;8663.436876603;8701.173388528206;8525.616347264388;8658.134186581417;8658.913410865936;8680.871486706814;8684.826303474021;2175.173434095065;2465.839720418167;2507.360623041113;2519.1686743375008;2645.4180080382284;832.883396324515;942.9009953954736;951.971870379988;1003.6687892995294;1009.6920439265947;16823.66352672964;20094.598108037757;20313.49216269605;21129.78870211309;21347;967.1440774914252;1003.007023047178;1016.6035246253966;1034.374203606822;1034.7620047389144;8500.44747091437;8982.955085224663;10363.322142984922;10387.740306492398;10572.788544229072;483393;583287;586155;588687;606999;150532;153026;153302.46697533104;160817.19591401969;164239;1079.2714917430703;1200.5557943560939;1260.6407173955379;1262.5707259531803;1264.1909178126007;21593.78406215918;21880;22644.320670379915;22758.31725048185;22876.08495660155;66503.00245496418;69005;69457.30542694508;69929;70227;86324.56837715772;86478;90003.54998224966;90990;95175;11235.213535052468;11390.430478475877;11397.145214108808;11519.366434846164;11523.193376463567;17392.739108913123;17909.731354029962;18268.634627307645;18398.81601183971;18451.630967380846;14603.780943286072;14760.852391475948;14814.851851481348;14819;14852.7772083421;12583.119181657195;13089.0837641368;13148.342582870628;13154.144980576231;13572.592822215349;15146.848531514544;15212.315445804745;15245.237738113272;15383.461578844686;15394.769078464056;11135.164862635173;11174.161937854531;11201.60794372193;11268.605598804003;11288.548458061585;14294.571162865132;14455.638609034859;14480.493182738554;14528.346224420138;14545.781813272597 06:07:33 INFO - PID 1657 | |3;mootools.html;1318.6505576022355;1441.9927900360497;1468.9779653305195;1474.0271420862232;1477.5124209011024;13082.476700931964;13490.797638035438;13588.388522516485;13611.93194034031;13625.046246762719;12930.806037909415;13188.604341869735;13306.800397994013;13317.53388631398;13337.53318633848;2708.8645567721633;2748.010716142197;2748.0244513197827;2751.312171957011;2751.3534319434916;2659.348459627394;2702.094798242591;2702.540568103417;2703.8377697338124;2711.5797051457057;2246.292417888368;2324.0587562037404;2659.627652128694;2713.145359211842;2717.402171522262;2267.2631394796676;2279.886005699708;2624.960625590618;2713.4844379567817;2722.768564672005;2690.932726681829;2696.083331667223;2701.9054333098443;2702.554133538567;2708.0386462805814;13056.543020994219;13169.078164528486;13174.934125329311;13177.143485673401;13180.736385272337;7347.559146451176;7641.770746877547;7641.808954776119;7658.119316278644;7665.271799179069;4490.259107247301;4723.858284251478;4794.544518270759;4800.711957282574;4832.299316599108;1371.5062577472202;1379.3034517568565;1379.620604333806;1399.3003498250876;1401.628568429367;19524.902375488175;19589.9020504898;19695;19723.802761972485;19773.703394448952;6385.96807015962;6510.934890651033;6543.476521878239;6621.735130594819;6635.601863888086;13983.230922299372;14020.509282175075;14025.438982440588;14025.929870350583;14055.929720351538;7268.236835132235;7291.161516425573;7395.519291246052;7399.223081576357;7421.1094668639025;5632.436756324335;6455;6880.449564034865;6881.346272104142;6887.827804304932;4116.300228961084;4739.620830333565;4905.950940490621;4931.827386041471;4975.601951843843;1407.2400903512194;1428.3857941085225;1431.1484666623342;1440.0495672855866;1460.941562337516;671.6809515480107;808.2241048593298;836.1137194573771;840.9243168114776;869.1178453869328;6101.877962440726;6652.634105124264;6883.552569082994;6963.9303606964295;7303.89044164338;664.6012392564422;685.9245482996866;696.2584847137782;698.1099098649263;708.8440543080617;4247.957520424818;5171.5862730981435;5232.5552328052545;5331.840044798662;5389.649672771257;14733;16131.919340403458;17566.121693915255;18129.637407251783;18318.992455414813;17663.293468261127;17701.937883727016;18468.630627387378;18517.81482185188;18531.629367412577;9044.231240344512;9254.676086336945;9509.144177024102;9524;9538.809223815484;7770;7968.561729104723;8041.879371809545;8132.75601731949;8391;14104.929475352557;14147.292635368396;16356.591085222964;16405.507834764976;16442.013479191286;13656.268746250591;15430;15994.8400515998;16742.748858766885;16742.916285418494;8676.479411235347;10440.947795260976;10682.83975740349;10841.457927103456;10926.180536459638;12507;12672.429740661877;12677.049221308254;12696.047796415121;12717;8613.784655383666;9394.95302523483;9762.951185244028;9841.409515429097;10235.283530152818;9355.719328420158;9665.468399238109;9784.9021509784;9834.21326293909;9947.801043979225;8748.212660860552;8869.689560865598;9015.053419391132;9044.954775226082;9279;5937.782754535224;6099.878002440015;6621.966890165711;6954.165500140016;6962.721491140298;5827.921834460638;6916.204636466669;6993.300669932966;7129;7181.317774811385 06:07:33 INFO - PID 1657 | |4;prototype.html;387.24487249862767;430.504919342756;462.03204287018696;465.1534207741909;470.4425256071556;6142.20151380321;6432.195975503062;6527.934720652792;6529.216494020712;6625.635590042546;6092.908606370896;6292.370762923705;6299.047295338448;6311.526635502344;6334.714937827797;4340.370646256291;4723.90552188956;4743.928841067387;4748.8575342739605;4772.785224664898;4144.316187829019;4599.609033232163;4753.429588449391;4760.33355330254;4767.594754445876;4482.193205223059;4757.690750101232;4777.426708794949;4779.378680771495;4782.306565547993;4760.595349395323;4770.833020844271;4773.403324584427;4777.474477807438;4785.282207668877;4114.938275925863;4146.958530414688;4168.749875007509;4192.748435093904;4216.882526130578;5098.643094983353;5287.9159772246985;6132.877342453171;6238.068107989237;6253.278250400877;5729;5730.34101078373;5734.885302293973;5740.885182296331;5785.537157027427;6660.067590537328;6966.477514186406;6967.442604591621;6973.63030466543;6988.091548098765;4702.27114797207;4817.313940120965;4823.589994850442;4828.155072862271;4838.153323168432;6712.228093769279;7242.927570724278;7336.413086953031;7343.485955983083;7358.300961408658;4683.578477937002;4876.122297986397;4894.238074293252;4914.680545764514;4918.458969513351;4980.555638864688;5002.474740152305;5007.349044624176;5008.424031236382;5008.57427118699;5021.271915572294;5699.401562835926;5705.885882282414;5713.8285851424525;5716.79991200306;5751.59738818287;6708.846579092157;6831.6242606656115;6985.161780586261;7030.578165309996;1689.923953422099;1692.255407620643;1702.5318037539776;1704.7272436410115;1712.5376148439852;1048.130052056791;1070.063694267516;1086.652271273185;1086.8369744538381;1136.5056200552785;6315.8105256841445;6398.200224971879;6414.262359828679;6421.5825971311715;6511.934880651228;7148.606826624482;7176.7298457417755;7198.424126070006;7203;7213.206547279795;5968.731407086691;6159.84600384985;6389.297177310492;6420.935790642034;6475.244358272191;2854.6717127530183;3135.639401468815;3150.6691797361404;3323.4017876781927;3677.7793332400142;5721.685307308138;6682.064510968468;7317.719399105135;7339.486235963538;7345.88981165283;6188.628682278988;6484.773032943825;6907.7236910524025;7313.158986716596;7380.889286660705;5771.538276937751;7254.854902902018;7295.088113985752;7341.375983041501;7343.706251749977;6276.995680691068;6438.7102580384935;7075.93860920866;7128.394086502705;7195.229836512875;6137.3862613738265;6468.15913931186;7049.823754406181;7066.4346852250665;7072.71709131629;6102.969485152724;6619.775341561826;6811.4550835934;6855.897161542481;7326.304001120098;4845.691663250832;4869.415670119608;4888.388951381077;4890.948446084109;4893.119238537098;7104.538205016657;7126.572405655677;7129.755961037612;7130.286971303036;7150.42796576283;5258.605604579595;5854.707264636836;6248.750049998131;6420.775272865428;6809.156797418563;5745.0987251437755;6403.775867844603;6579.309172537006;6812.659367031728;6821.454283657395;5355.625106242525;6673.366030227121;6705.295943926013;6725.865482690221;6770.4922130839395;5965.492933100735;6546.705398256994;6566.946369938002;6720.462363011045;6833.453323734188;6599.868002640016;6603.759447322234;6609.603423794588;6623.966880165568;6659.7003134860015 06:07:33 INFO - PID 1657 | |5;yui.html;598.8023952095806;699.6956323999058;744.3821628048719;766.6511737159591;817.9427440079193;16156.272967716452;16876.98738075715;17111.22999465024;17140.914295428505;17146.571335716613;10123.341982771115;12900.54848080315;12969.740605187913;13147.934260328684;13478.865211347884;547.8958997790407;660.3627499463017;834.8539005674013;967.2552134856156;967.7870868408938;989.5992123190083;991.4546999150474;992.4392718114278;994.5325696922434;995.2137811129236;1025.4975062219496;1036.1400037968506;1038.890916453773;1039.365986748083;1043.519980808829;950.7100334398006;1034.6533911139777;1037.3205550364523;1038;1041.3543602966133;776.97669069928;782.3545574900729;784.1139512351064;786.5516655506364;790.4782843323379;15921.840781592151;16345.101019443926;17599.912000439916;17631.206595703225;17647.294108235656;17104.230309636096;17481.825181748274;17643.558911027198;17707.93752374849;17717.202725877367;14364.425422983068;14513;14785.630359240997;14848.851511484854;14969.952103352769;3658.70730341572;3697.2975134724584;3701.796401197933;3702.37059699849;3709.1839795245005;112340;118707;127045.09432358522;138182;139970;11187.552497900155;11430.542778288942;11532.88467115335;13574.660633484242;14112.082714623517;25821.967121314938;25957.22128336153;26392.60411093835;26513.867430663107;26543.601845972327;7551.169371369145;7573.659185336673;7589.468737188344;7591.772246832604;7605.315521602972;3880.1463677990796;3919.1573811630637;3921.9607803922167;3949.4865667463046;3975.940360894589;4558.749268790249;4679.789409476582;4708.293755936568;4709.835155769531;4718.504557021532;964.1370972979284;994.3487016004528;999.4053538144792;1003.3377970539409;1003.5885287032281;642.9389208025231;643.9935600644027;644.7453255963868;662.9734810607619;670.4167374384219;11117.22179447431;11173.608923687632;12221.633350999484;12607.621771346874;12672.873271267354;573.438030729887;611.6605284067297;627.5481653209682;634.2864277687602;671.1308855032744;5464.480874316933;5529.861753456115;5578.9163162552595;5729.914051289234;5741.224934633855;126007.7399225996;130100;144339;145555;148616.25691871688;100570;117697;118940;120975.39512302558;126311;86842.56578717066;93567.53216233876;95137.57293640601;100060.99939000662;103790.48104759428;9094;10397.428141452292;11827.82258266144;11979.221350612183;12401.75196496047;35222.647773522964;38835.805820970716;42449;43806;44953.32570011518;82504.58747706223;82727;85349.57325213334;87883.12116879005;89439;16095.615981972389;16662.50012499627;16708.749368759236;16967.321307147577;17076.573085672957;18376.908115459337;18727.531811704815;19144.521386965436;19426;19729.21083156715;16803.495895123167;17407.30370785205;17416.216270267614;17753.467395978143;17781.555461113574;16598.17009149562;17866.91066544659;18213.542916566377;18308.542286442946;18333.908330458264;16624;16986.320547177973;17210.91394543019;17421.825781742526;17439.912800435915;9686.660966866126;11286.266392684449;11340.94329528347;12021.398930053638;12465.875341246472;12466.43901024438;13853.930730346283;14111.435542578181;14429.35067921967;14597.781033284296 06:07:33 INFO - PID 1657 | __end_tp_report 06:07:33 INFO - PID 1657 | __start_cc_report 06:07:33 INFO - PID 1657 | _x_x_mozilla_cycle_collect,468 06:07:33 INFO - PID 1657 | __end_cc_report 06:07:33 INFO - PID 1657 | __startTimestamp1497272853312__endTimestamp 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | ------- Summary: start ------- 06:07:33 INFO - PID 1657 | Number of tests: 6 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | [#0] dojo.html Cycles:165 Average:6082.06 Median:6322.40 stddev:745.56 (11.8%) stddev-sans-first:719.09 06:07:33 INFO - PID 1657 | Values: 3468.2 3496.1 3587.5 3590.3 3590.9 6064.8 6079.5 6103.6 6151.8 6167.0 6113.9 6175.0 6203.0 6221.5 6237.7 4400.9 5372.0 5372.9 5372.9 5498.1 5404.3 5455.8 5478.3 5498.2 5504.2 5305.5 5313.4 5341.1 5422.7 5423.7 5368.5 5435.1 5435.4 5457.5 5475.5 4379.0 5358.7 5394.0 5406.8 5463.2 5062.8 5087.3 6115.6 6219.7 6231.9 5749.0 6386.2 6611.0 6654.5 6665.2 6004.5 6098.1 6599.5 6661.1 6793.5 6269.9 6297.4 6322.4 6346.3 6350.2 6897.8 6911.0 6923.4 6933.7 6935.3 6331.8 6388.0 6391.1 6413.9 6568.6 6578.7 6590.1 6689.3 6702.6 6711.6 6612.1 6613.2 6619.2 6636.5 6677.8 6381.6 6461.3 6626.7 6634.9 6642.5 5279.6 5318.4 5332.1 5347.0 5363.0 4722.6 4778.4 4805.6 4824.3 4867.2 4674.0 6140.0 6326.7 6334.6 6343.6 6714.6 6767.6 6777.8 6797.0 6803.9 6209.0 6260.5 6305.7 6313.2 6322.3 5754.4 5774.0 5795.0 5817.4 5858.6 6813.7 6821.4 6843.4 6879.6 6918.5 6730.4 6736.0 6755.9 6760.8 6768.6 6837.2 6868.0 6889.5 6904.8 6905.4 6356.8 6453.1 6597.3 6600.1 6621.1 6574.6 6588.4 6641.9 6709.1 6721.7 5579.6 6290.9 6448.4 6474.7 6478.1 6382.1 6449.5 6452.4 6458.6 6461.6 6228.8 6239.5 6307.3 6321.2 6339.1 6375.6 6376.9 6434.5 6445.1 6453.6 5620.3 6055.0 6251.5 6279.2 6323.1 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | [#1] ext.html Cycles:180 Average:42590.39 Median:175089.33 stddev:74283.23 (42.4%) stddev-sans-first:74424.78 06:07:33 INFO - PID 1657 | Values: 622.6 737.4 815.4 835.2 850.3 36506.6 37345.8 37570.0 37616.0 37757.0 37006.3 37349.0 37354.6 37438.1 37454.8 6413.9 7207.9 7330.2 7332.2 7337.0 6332.7 7117.7 7369.3 7404.2 7407.3 7900.5 8118.3 8118.5 8128.5 8140.3 8082.8 8144.1 8152.2 8161.8 8170.8 7812.5 7863.5 7870.4 7883.8 7897.2 34757.0 34762.5 34772.7 34791.5 34911.8 3504.9 3516.9 3548.0 3550.3 3550.3 29242.7 29385.4 29431.1 29439.6 29481.1 3027.3 3035.7 3069.0 3081.4 3087.0 236434.0 240432.0 240739.0 304728.0 305748.0 46439.5 47137.6 47253.0 47383.8 47538.8 49462.3 49509.5 49578.3 49591.5 49604.5 13700.7 13834.6 13853.3 13859.5 13867.0 11115.7 11129.7 11226.0 11248.8 11250.4 13442.5 13452.9 13503.5 13508.7 13570.9 5353.7 5656.4 5920.8 5952.2 5983.6 980.2 981.7 983.8 1042.5 1053.1 18790.0 21954.9 22073.2 22269.7 22909.4 1069.0 1105.5 1106.5 1111.7 1131.9 11174.4 11242.6 11527.4 11871.0 12047.2 307141.0 310722.0 313574.0 315270.0 316205.0 52982.2 53109.7 53183.0 53256.7 53361.0 1458.1 1501.1 1507.4 1511.8 1516.2 21620.1 21804.1 22047.6 22230.9 22341.8 29790.7 37637.6 37788.1 37820.0 37906.1 265608.0 266578.0 266877.0 266895.0 267055.0 28507.0 29403.9 29618.5 34050.8 34179.0 35777.8 40306.4 41343.6 42000.4 42102.4 27166.3 27823.7 28134.6 28210.0 28565.4 28732.0 28828.3 30144.5 30553.8 30608.2 33973.7 36114.1 36119.7 36132.8 36192.6 20459.0 27958.0 28954.4 29003.0 30159.7 20619.7 21718.0 23378.9 23389.5 23403.0 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | [#2] jquery.html Cycles:180 Average:38947.66 Median:2195.23 stddev:98266.74 (4476.4%) stddev-sans-first:98500.00 06:07:33 INFO - PID 1657 | Values: 495.5 500.3 501.4 516.0 520.1 21025.7 21734.9 21752.0 22349.0 22557.8 16831.7 22974.4 23208.4 23589.0 23632.4 6057.5 6252.7 6255.2 6268.5 6269.1 6134.9 6250.6 6251.4 6254.8 6261.1 5466.0 5769.7 6165.4 6187.9 6219.1 6042.5 6145.8 6149.9 6186.1 6195.1 5577.6 5582.4 5584.3 5586.6 5597.3 26721.7 28829.8 29306.4 29344.4 29367.9 2117.4 2194.7 2216.9 2220.0 2230.3 17861.8 18348.3 18368.1 18487.4 18600.8 2139.3 2163.6 2191.3 2195.8 2200.3 158053.0 165392.0 166191.0 179784.0 205903.0 13958.8 14167.7 14433.3 14689.6 14712.3 19004.2 19051.0 19055.8 19063.0 19068.6 9253.0 9368.8 9387.2 9420.6 9475.8 8647.0 8650.7 8653.0 8663.4 8701.2 8525.6 8658.1 8658.9 8680.9 8684.8 2175.2 2465.8 2507.4 2519.2 2645.4 832.9 942.9 952.0 1003.7 1009.7 16823.7 20094.6 20313.5 21129.8 21347.0 967.1 1003.0 1016.6 1034.4 1034.8 8500.4 8983.0 10363.3 10387.7 10572.8 483393.0 583287.0 586155.0 588687.0 606999.0 150532.0 153026.0 153302.5 160817.2 164239.0 1079.3 1200.6 1260.6 1262.6 1264.2 21593.8 21880.0 22644.3 22758.3 22876.1 66503.0 69005.0 69457.3 69929.0 70227.0 86324.6 86478.0 90003.5 90990.0 95175.0 11235.2 11390.4 11397.1 11519.4 11523.2 17392.7 17909.7 18268.6 18398.8 18451.6 14603.8 14760.9 14814.9 14819.0 14852.8 12583.1 13089.1 13148.3 13154.1 13572.6 15146.8 15212.3 15245.2 15383.5 15394.8 11135.2 11174.2 11201.6 11268.6 11288.5 14294.6 14455.6 14480.5 14528.3 14545.8 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | [#3] mootools.html Cycles:180 Average:8060.80 Median:2735.39 stddev:5329.26 (194.8%) stddev-sans-first:5320.13 06:07:33 INFO - PID 1657 | Values: 1318.7 1442.0 1469.0 1474.0 1477.5 13082.5 13490.8 13588.4 13611.9 13625.0 12930.8 13188.6 13306.8 13317.5 13337.5 2708.9 2748.0 2748.0 2751.3 2751.4 2659.3 2702.1 2702.5 2703.8 2711.6 2246.3 2324.1 2659.6 2713.1 2717.4 2267.3 2279.9 2625.0 2713.5 2722.8 2690.9 2696.1 2701.9 2702.6 2708.0 13056.5 13169.1 13174.9 13177.1 13180.7 7347.6 7641.8 7641.8 7658.1 7665.3 4490.3 4723.9 4794.5 4800.7 4832.3 1371.5 1379.3 1379.6 1399.3 1401.6 19524.9 19589.9 19695.0 19723.8 19773.7 6386.0 6510.9 6543.5 6621.7 6635.6 13983.2 14020.5 14025.4 14025.9 14055.9 7268.2 7291.2 7395.5 7399.2 7421.1 5632.4 6455.0 6880.4 6881.3 6887.8 4116.3 4739.6 4906.0 4931.8 4975.6 1407.2 1428.4 1431.1 1440.0 1460.9 671.7 808.2 836.1 840.9 869.1 6101.9 6652.6 6883.6 6963.9 7303.9 664.6 685.9 696.3 698.1 708.8 4248.0 5171.6 5232.6 5331.8 5389.6 14733.0 16131.9 17566.1 18129.6 18319.0 17663.3 17701.9 18468.6 18517.8 18531.6 9044.2 9254.7 9509.1 9524.0 9538.8 7770.0 7968.6 8041.9 8132.8 8391.0 14104.9 14147.3 16356.6 16405.5 16442.0 13656.3 15430.0 15994.8 16742.7 16742.9 8676.5 10440.9 10682.8 10841.5 10926.2 12507.0 12672.4 12677.0 12696.0 12717.0 8613.8 9395.0 9763.0 9841.4 10235.3 9355.7 9665.5 9784.9 9834.2 9947.8 8748.2 8869.7 9015.1 9045.0 9279.0 5937.8 6099.9 6622.0 6954.2 6962.7 5827.9 6916.2 6993.3 7129.0 7181.3 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | [#4] prototype.html Cycles:180 Average:5549.68 Median:6151.02 stddev:1703.63 (27.7%) stddev-sans-first:1663.76 06:07:33 INFO - PID 1657 | Values: 387.2 430.5 462.0 465.2 470.4 6142.2 6432.2 6527.9 6529.2 6625.6 6092.9 6292.4 6299.0 6311.5 6334.7 4340.4 4723.9 4743.9 4748.9 4772.8 4144.3 4599.6 4753.4 4760.3 4767.6 4482.2 4757.7 4777.4 4779.4 4782.3 4760.6 4770.8 4773.4 4777.5 4785.3 4114.9 4147.0 4168.7 4192.7 4216.9 5098.6 5287.9 6132.9 6238.1 6253.3 5729.0 5730.3 5734.9 5740.9 5785.5 6660.1 6966.5 6967.4 6973.6 6988.1 4702.3 4817.3 4823.6 4828.2 4838.2 6712.2 7242.9 7336.4 7343.5 7358.3 4683.6 4876.1 4894.2 4914.7 4918.5 4980.6 5002.5 5007.3 5008.4 5008.6 5021.3 5699.4 5705.9 5713.8 5716.8 5751.6 6708.8 6831.6 6985.2 7030.6 1689.9 1692.3 1702.5 1704.7 1712.5 1048.1 1070.1 1086.7 1086.8 1136.5 6315.8 6398.2 6414.3 6421.6 6511.9 7148.6 7176.7 7198.4 7203.0 7213.2 5968.7 6159.8 6389.3 6420.9 6475.2 2854.7 3135.6 3150.7 3323.4 3677.8 5721.7 6682.1 7317.7 7339.5 7345.9 6188.6 6484.8 6907.7 7313.2 7380.9 5771.5 7254.9 7295.1 7341.4 7343.7 6277.0 6438.7 7075.9 7128.4 7195.2 6137.4 6468.2 7049.8 7066.4 7072.7 6103.0 6619.8 6811.5 6855.9 7326.3 4845.7 4869.4 4888.4 4890.9 4893.1 7104.5 7126.6 7129.8 7130.3 7150.4 5258.6 5854.7 6248.8 6420.8 6809.2 5745.1 6403.8 6579.3 6812.7 6821.5 5355.6 6673.4 6705.3 6725.9 6770.5 5965.5 6546.7 6566.9 6720.5 6833.5 6599.9 6603.8 6609.6 6624.0 6659.7 06:07:33 INFO - PID 1657 | 06:07:33 INFO - PID 1657 | [#5] yui.html Cycles:180 Average:24792.94 Median:17767.51 stddev:37356.28 (210.3%) stddev-sans-first:37416.91 06:07:33 INFO - PID 1657 | Values: 598.8 699.7 744.4 766.7 817.9 16156.3 16877.0 17111.2 17140.9 17146.6 10123.3 12900.5 12969.7 13147.9 13478.9 547.9 660.4 834.9 967.3 967.8 989.6 991.5 992.4 994.5 995.2 1025.5 1036.1 1038.9 1039.4 1043.5 950.7 1034.7 1037.3 1038.0 1041.4 777.0 782.4 784.1 786.6 790.5 15921.8 16345.1 17599.9 17631.2 17647.3 17104.2 17481.8 17643.6 17707.9 17717.2 14364.4 14513.0 14785.6 14848.9 14970.0 3658.7 3697.3 3701.8 3702.4 3709.2 112340.0 118707.0 127045.1 138182.0 139970.0 11187.6 11430.5 11532.9 13574.7 14112.1 25822.0 25957.2 26392.6 26513.9 26543.6 7551.2 7573.7 7589.5 7591.8 7605.3 3880.1 3919.2 3922.0 3949.5 3975.9 4558.7 4679.8 4708.3 4709.8 4718.5 964.1 994.3 999.4 1003.3 1003.6 642.9 644.0 644.7 663.0 670.4 11117.2 11173.6 12221.6 12607.6 12672.9 573.4 611.7 627.5 634.3 671.1 5464.5 5529.9 5578.9 5729.9 5741.2 126007.7 130100.0 144339.0 145555.0 148616.3 100570.0 117697.0 118940.0 120975.4 126311.0 86842.6 93567.5 95137.6 100061.0 103790.5 9094.0 10397.4 11827.8 11979.2 12401.8 35222.6 38835.8 42449.0 43806.0 44953.3 82504.6 82727.0 85349.6 87883.1 89439.0 16095.6 16662.5 16708.7 16967.3 17076.6 18376.9 18727.5 19144.5 19426.0 19729.2 16803.5 17407.3 17416.2 17753.5 17781.6 16598.2 17866.9 18213.5 18308.5 18333.9 16624.0 16986.3 17210.9 17421.8 17439.9 9686.7 11286.3 11340.9 12021.4 12465.9 12466.4 13853.9 14111.4 14429.4 14597.8 06:07:33 INFO - PID 1657 | -------- Summary: end -------- 06:07:33 INFO - PID 1657 | 06:07:33 INFO - TEST-INFO | 1657: exit 0 06:07:33 INFO - TEST-OK | dromaeo_css | took 1143466ms 06:07:33 INFO - TEST-START | kraken 06:07:33 INFO - operating with platform_type : mac_ 06:07:33 INFO - Initialising browser for kraken test... 06:07:33 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpqNaBzF/profile 06:07:33 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 06:08:07 INFO - TEST-INFO | 1665: killed by SIGTERM 06:08:07 INFO - Browser initialized. 06:08:07 INFO - Running cycle 1/1 for kraken test... 06:08:07 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpqNaBzF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:09 INFO - PID 1669 | 2017-06-12 06:08:09.466 plugin-container[1670:12115] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b4b, name = 'com.apple.tsm.portname' 06:08:09 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:09 INFO - PID 1669 | 2017-06-12 06:08:09.466 plugin-container[1670:12115] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b4f, name = 'com.apple.CFPasteboardClient' 06:08:09 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:09 INFO - PID 1669 | 2017-06-12 06:08:09.466 plugin-container[1670:12115] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:10 INFO - PID 1669 | 2017-06-12 06:08:10.310 plugin-container[1671:12161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 06:08:10 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:10 INFO - PID 1669 | 2017-06-12 06:08:10.311 plugin-container[1671:12161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 06:08:10 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:10 INFO - PID 1669 | 2017-06-12 06:08:10.311 plugin-container[1671:12161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:19 INFO - PID 1669 | RSS: Main: 253710336 06:08:19 INFO - PID 1669 | 06:08:22 INFO - PID 1669 | 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) 06:08:23 INFO - PID 1669 | RSS: Main: 265216000 06:08:23 INFO - PID 1669 | 06:08:26 INFO - PID 1669 | 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) 06:08:26 INFO - PID 1669 | RSS: Main: 275742720 06:08:26 INFO - PID 1669 | 06:08:30 INFO - PID 1669 | 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) 06:08:30 INFO - PID 1669 | RSS: Main: 279609344 06:08:30 INFO - PID 1669 | 06:08:32 INFO - PID 1669 | 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) 06:08:33 INFO - PID 1669 | RSS: Main: 281100288 06:08:33 INFO - PID 1669 | 06:08:35 INFO - PID 1669 | 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) 06:08:35 INFO - PID 1669 | RSS: Main: 281550848 06:08:35 INFO - PID 1669 | 06:08:38 INFO - PID 1669 | 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) 06:08:39 INFO - PID 1669 | RSS: Main: 287485952 06:08:39 INFO - PID 1669 | 06:08:42 INFO - PID 1669 | 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) 06:08:42 INFO - PID 1669 | RSS: Main: 289316864 06:08:42 INFO - PID 1669 | 06:08:45 INFO - PID 1669 | 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) 06:08:46 INFO - PID 1669 | RSS: Main: 288075776 06:08:46 INFO - PID 1669 | 06:08:47 INFO - PID 1669 | 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) 06:08:48 INFO - PID 1669 | RSS: Main: 288116736 06:08:48 INFO - PID 1669 | 06:08:50 INFO - PID 1669 | 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) 06:08:50 INFO - PID 1669 | RSS: Main: 287805440 06:08:50 INFO - PID 1669 | 06:08:52 INFO - PID 1669 | 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) 06:08:53 INFO - PID 1669 | RSS: Main: 287789056 06:08:53 INFO - PID 1669 | 06:08:56 INFO - PID 1669 | 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) 06:08:57 INFO - PID 1669 | RSS: Main: 287850496 06:08:57 INFO - PID 1669 | 06:09:00 INFO - PID 1669 | 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) 06:09:00 INFO - PID 1669 | RSS: Main: 288411648 06:09:00 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | 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) 06:09:02 INFO - PID 1669 | RSS: Main: 289865728 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | __start_tp_report 06:09:02 INFO - PID 1669 | _x_x_mozilla_page_load 06:09:02 INFO - PID 1669 | _x_x_mozilla_page_load_details 06:09:02 INFO - PID 1669 | |i|pagename|runs| 06:09:02 INFO - PID 1669 | |0;ai-astar;108;164;130;121;116;113;114;117;135;173 06:09:02 INFO - PID 1669 | |1;audio-beat-detection;152;149;145;145;108;126;112;152;148;112 06:09:02 INFO - PID 1669 | |2;audio-dft;208;201;204;208;217;217;215;200;198;192 06:09:02 INFO - PID 1669 | |3;audio-fft;70;77;73;121;57;62;67;81;106;85 06:09:02 INFO - PID 1669 | |4;audio-oscillator;71;67;110;70;76;77;126;71;73;124 06:09:02 INFO - PID 1669 | |5;imaging-gaussian-blur;88;89;89;93;89;89;88;95;88;88 06:09:02 INFO - PID 1669 | |6;imaging-darkroom;95;97;95;96;97;106;96;95;97;96 06:09:02 INFO - PID 1669 | |7;imaging-desaturate;91;96;92;92;91;91;91;95;92;91 06:09:02 INFO - PID 1669 | |8;json-parse-financial;51;52;52;53;55;55;51;53;51;54 06:09:02 INFO - PID 1669 | |9;json-stringify-tinderbox;60;60;63;81;59;65;55;58;56;57 06:09:02 INFO - PID 1669 | |10;stanford-crypto-aes;80;78;81;82;81;83;82;84;81;81 06:09:02 INFO - PID 1669 | |11;stanford-crypto-ccm;145;139;138;135;137;160;146;166;156;173 06:09:02 INFO - PID 1669 | |12;stanford-crypto-pbkdf2;188;183;186;187;186;183;184;183;213;188 06:09:02 INFO - PID 1669 | |13;stanford-crypto-sha256-iterative;62;92;77;60;91;88;91;101;99;66 06:09:02 INFO - PID 1669 | __end_tp_report 06:09:02 INFO - PID 1669 | __start_cc_report 06:09:02 INFO - PID 1669 | _x_x_mozilla_cycle_collect,1185 06:09:02 INFO - PID 1669 | __end_cc_report 06:09:02 INFO - PID 1669 | __startTimestamp1497272942960__endTimestamp 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | ------- Summary: start ------- 06:09:02 INFO - PID 1669 | Number of tests: 14 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#0] ai-astar Cycles:10 Average:129.10 Median:125.50 stddev:22.35 (17.8%) stddev-sans-first:22.37 06:09:02 INFO - PID 1669 | Values: 108.0 164.0 130.0 121.0 116.0 113.0 114.0 117.0 135.0 173.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:146.50 stddev:18.29 (12.5%) stddev-sans-first:18.32 06:09:02 INFO - PID 1669 | Values: 152.0 149.0 145.0 145.0 108.0 126.0 112.0 152.0 148.0 112.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#2] audio-dft Cycles:10 Average:206.00 Median:208.00 stddev:8.54 (4.1%) stddev-sans-first:9.02 06:09:02 INFO - PID 1669 | Values: 208.0 201.0 204.0 208.0 217.0 217.0 215.0 200.0 198.0 192.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#3] audio-fft Cycles:10 Average:79.90 Median:71.50 stddev:19.90 (27.8%) stddev-sans-first:20.78 06:09:02 INFO - PID 1669 | Values: 70.0 77.0 73.0 121.0 57.0 62.0 67.0 81.0 106.0 85.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:71.00 stddev:23.65 (33.3%) stddev-sans-first:24.41 06:09:02 INFO - PID 1669 | Values: 71.0 67.0 110.0 70.0 76.0 77.0 126.0 71.0 73.0 124.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:89.00 stddev:2.41 (2.7%) stddev-sans-first:2.49 06:09:02 INFO - PID 1669 | Values: 88.0 89.0 89.0 93.0 89.0 89.0 88.0 95.0 88.0 88.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:96.00 stddev:3.27 (3.4%) stddev-sans-first:3.38 06:09:02 INFO - PID 1669 | Values: 95.0 97.0 95.0 96.0 97.0 106.0 96.0 95.0 97.0 96.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:92.00 stddev:1.81 (2.0%) stddev-sans-first:1.87 06:09:02 INFO - PID 1669 | Values: 91.0 96.0 92.0 92.0 91.0 91.0 91.0 95.0 92.0 91.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.57 (3.0%) stddev-sans-first:1.54 06:09:02 INFO - PID 1669 | Values: 51.0 52.0 52.0 53.0 55.0 55.0 51.0 53.0 51.0 54.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:60.00 stddev:7.53 (12.6%) stddev-sans-first:7.97 06:09:02 INFO - PID 1669 | Values: 60.0 60.0 63.0 81.0 59.0 65.0 55.0 58.0 56.0 57.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:1.64 (2.0%) stddev-sans-first:1.67 06:09:02 INFO - PID 1669 | Values: 80.0 78.0 81.0 82.0 81.0 83.0 82.0 84.0 81.0 81.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:151.00 stddev:13.41 (8.9%) stddev-sans-first:14.12 06:09:02 INFO - PID 1669 | Values: 145.0 139.0 138.0 135.0 137.0 160.0 146.0 166.0 156.0 173.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.10 Median:186.50 stddev:8.97 (4.8%) stddev-sans-first:9.52 06:09:02 INFO - PID 1669 | Values: 188.0 183.0 186.0 187.0 186.0 183.0 184.0 183.0 213.0 188.0 06:09:02 INFO - PID 1669 | 06:09:02 INFO - PID 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.70 Median:89.50 stddev:15.30 (17.1%) stddev-sans-first:14.28 06:09:02 INFO - PID 1669 | Values: 62.0 92.0 77.0 60.0 91.0 88.0 91.0 101.0 99.0 66.0 06:09:02 INFO - PID 1669 | -------- Summary: end -------- 06:09:02 INFO - PID 1669 | 06:09:03 INFO - TEST-INFO | 1669: exit 0 06:09:04 INFO - TEST-OK | kraken | took 90107ms 06:09:04 INFO - SUITE-END | took 1233s 06:09:04 INFO - Completed test suite (00:20:33) 06:09:04 INFO - javascript benchmark 06:09:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8144.333478928132, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3468.167639766457, 3496.125968507873, 3587.4618807178927, 3590.2819436112786, 3590.910227244319, 6064.78773242936, 6079.544034197436, 6103.60326578772, 6151.81544553663, 6166.951618224902, 6113.877722445548, 6174.950258456062, 6203.038529028008, 6221.533384996121, 6237.688115594214, 4400.933985990197, 5372.0, 5372.892542149164, 5372.91940620891, 5498.065328894098, 5404.32445944257, 5455.781768729246, 5478.342598888119, 5498.230247765316, 5504.2294078829, 5305.522502974751, 5313.44208858072, 5341.092014357569, 5422.728863556826, 5423.701696406716, 5368.543673787733, 5435.076037073707, 5435.402105768402, 5457.481539253765, 5475.479829416239, 4378.978105109454, 5358.705271210082, 5394.02907476654, 5406.83779486616, 5463.180522921554, 5062.848114556569, 5087.313212716273, 6115.602485838406, 6219.720112594944, 6231.937680623181, 5749.022666146723, 6386.201724784402, 6611.0, 6654.467642588581, 6665.166854143232, 6004.459598636144, 6098.14625952367, 6599.5380323377385, 6661.100751398548, 6793.524453288272, 6269.905951410732, 6297.4017468340435, 6322.399372059647, 6346.333634968354, 6350.23797144341, 6897.7585784497305, 6910.965445172742, 6923.446124310043, 6933.7226510939, 6935.3411425914455, 6331.778387756406, 6387.968060159762, 6391.1371964785085, 6413.935860641333, 6568.638724870082, 6578.703958321887, 6590.110335104795, 6689.331066893272, 6702.597844129368, 6711.63086030273, 6612.107365505595, 6613.206415230203, 6619.238787539494, 6636.535442518977, 6677.833054173684, 6381.553291269566, 6461.25695545009, 6626.668666566652, 6634.933650663432, 6642.535022548472, 5279.551238144724, 5318.361796584357, 5332.093544097513, 5347.037533243975, 5363.034653762282, 4722.6221902247735, 4778.37881075458, 4805.567498925114, 4824.34871292378, 4867.1969125093965, 4673.953260467351, 6139.956207444745, 6326.715297811517, 6334.588251763621, 6343.619382837044, 6714.597124172467, 6767.593944363354, 6777.762778302835, 6797.0, 6803.863922721518, 6209.0, 6260.467860231931, 6305.653189074645, 6313.2424109107205, 6322.304546499881, 5754.424557544212, 5773.971130144322, 5794.971025144931, 5817.418258174233, 5858.648481091148, 6813.659317034128, 6821.351971562793, 6843.384095431535, 6879.552829066095, 6918.481113916376, 6730.360615741497, 6736.02327662496, 6755.898661520179, 6760.797176084725, 6768.56004359715, 6837.247902730596, 6868.038474613458, 6889.517733758789, 6904.827379315558, 6905.413039891666, 6356.809295721136, 6453.09656648079, 6597.307282735435, 6600.141981542369, 6621.106150669598, 6574.572652777554, 6588.439982601532, 6641.900371494528, 6709.094272273181, 6721.697523611547, 5579.637323573955, 6290.899092658858, 6448.387403196689, 6474.708638111202, 6478.060681201106, 6382.053473690383, 6449.4840412765925, 6452.419282264619, 6458.580192287486, 6461.57999730016, 6228.813135605939, 6239.532035097477, 6307.274663413767, 6321.209848768904, 6339.144215530844, 6375.6174629522375, 6376.936230637634, 6434.517411194086, 6445.097686323825, 6453.580517266362, 5620.325560932714, 6055.031195008777, 6251.46862516673, 6279.246490421178, 6323.114763932961], "value": 6033.366233790996, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.5797586629025, 737.4248086492536, 815.4129027100486, 835.2190701693914, 850.3452341696893, 36506.634933650654, 37345.81327093364, 37570.0, 37616.0, 37756.99999999996, 37006.25987480249, 37349.0, 37354.62645373545, 37438.0640483988, 37454.81272593633, 6413.903791443132, 7207.927920720778, 7330.193678695339, 7332.1934587195365, 7336.963315183417, 6332.651704156269, 7117.679704413314, 7369.336759691627, 7404.1855395906405, 7407.259274072603, 7900.525968441912, 8118.309943654796, 8118.512889226666, 8128.471649342803, 8140.308073813733, 8082.757517274491, 8144.063432705197, 8152.18478152186, 8161.836763264731, 8170.836583268331, 7812.492188007761, 7863.528188308663, 7870.409719271078, 7883.842323153504, 7897.170797066283, 34757.0, 34762.478562821576, 34772.65227347719, 34791.4781278281, 34911.825440872635, 3504.912377190565, 3516.9296614067575, 3547.9822600886832, 3550.289942011608, 3550.3076900004507, 29242.70757292421, 29385.41229175426, 29431.11706648804, 29439.558406623917, 29481.115566533037, 3027.3188532579993, 3035.741961933238, 3069.048594935555, 3081.3683194944983, 3086.9691303086906, 236434.0, 240432.0, 240739.0, 304728.0, 305748.0, 46439.535604644196, 47137.585872423195, 47252.99999999931, 47383.81540461516, 47538.76230618825, 49462.258066128314, 49509.50490495121, 49578.25632615513, 49591.50408495941, 49604.50395496071, 13700.65748356299, 13834.584962451143, 13853.307334633228, 13859.514916977858, 13867.0, 11115.666530003951, 11129.666110016713, 11225.989660930556, 11248.775024499628, 11250.381229032319, 13442.529511467052, 13452.865471345161, 13503.459861605426, 13508.662283442796, 13570.86429135696, 5353.71510837404, 5656.434356564393, 5920.822375328747, 5952.226210592596, 5983.611065280743, 980.1775265692809, 981.6613268422383, 983.8179936711731, 1042.5152304178594, 1053.1101219469535, 18790.0, 21954.89022554909, 22073.227437039626, 22269.66595501069, 22909.42726431819, 1069.0, 1105.5412004018403, 1106.5131342209563, 1111.6776134920783, 1131.8585176852894, 11174.44127793607, 11242.618171036114, 11527.365994870363, 11871.0, 12047.156699030984, 307141.0, 310722.0, 313574.0, 315270.0, 316205.0, 52982.205266921024, 53109.67225819385, 53183.0, 53256.73371633117, 53361.0, 1458.1105525629357, 1501.06183635228, 1507.4045751927927, 1511.8261399938929, 1516.1812621184438, 21620.13519459204, 21804.127834887062, 22047.57476677532, 22230.888455576835, 22341.77658223462, 29790.702092978794, 37637.623623764506, 37788.05529861776, 37820.0, 37906.052348691504, 265608.0, 266578.0, 266877.0, 266895.0, 267055.0, 28507.0, 29403.85298073496, 29618.519074046642, 34050.829745851115, 34179.0, 35777.82111089532, 40306.38774448989, 41343.58656413518, 42000.369994449495, 42102.36846447244, 27166.320841979108, 27823.721762782112, 28134.577981329887, 28210.0, 28565.42869142647, 28731.988760786546, 28828.279293017844, 30144.547831782103, 30553.847230763702, 30608.234794130323, 33973.66026339804, 36114.09714757152, 36119.73580924655, 36132.81933590315, 36192.63807361998, 20459.0, 27958.021469248477, 28954.42091158123, 29002.984895528556, 30159.69840301569, 20619.690704639142, 21718.0, 23378.883105584362, 23389.532209356057, 23403.0], "value": 16574.932541010596, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.45747406589794, 500.29458463566357, 501.4058340866073, 515.9716215608141, 520.0924386944782, 21025.68461473079, 21734.89132554339, 21752.0, 22349.0, 22557.774422255792, 16831.74752378715, 22974.425639359026, 23208.419789505228, 23589.0, 23632.409189770264, 6057.54568407369, 6252.6561039142825, 6255.218097737783, 6268.467180289681, 6269.090981807635, 6134.877302453949, 6250.593711408744, 6251.437370636641, 6254.781082662108, 6261.092141639459, 5466.043442397585, 5769.653820770767, 6165.445109940105, 6187.876242475148, 6219.098230756537, 6042.456178943894, 6145.846353841145, 6149.938500615003, 6186.133941248228, 6195.0707393890825, 5577.581681373913, 5582.385937546866, 5584.301962254718, 5586.636868603528, 5597.328320601513, 26721.73278267212, 28829.84680612773, 29306.41387172266, 29344.41311173786, 29367.853160734274, 2117.4388786971463, 2194.692743015979, 2216.900239489227, 2219.966700499494, 2230.275160572804, 17861.821381786147, 18348.26606935721, 18368.081595920285, 18487.445376638723, 18600.813991860043, 2139.3154190658997, 2163.610550100981, 2191.3425972208274, 2195.769444208351, 2200.2739096098408, 158053.0, 165392.0, 166191.0, 179783.9999999987, 205903.0, 13958.790618140736, 14167.71664566703, 14433.278336083154, 14689.632759180893, 14712.337944792296, 19004.23983040663, 19051.0, 19055.80944190568, 19063.0, 19068.61862762737, 9253.028432014677, 9368.812623747486, 9387.249020078378, 9420.576074076684, 9475.76310592227, 8647.0, 8650.653973840976, 8653.004904436071, 8663.436876603, 8701.173388528206, 8525.616347264388, 8658.134186581417, 8658.913410865936, 8680.871486706814, 8684.826303474021, 2175.173434095065, 2465.839720418167, 2507.360623041113, 2519.1686743375008, 2645.4180080382284, 832.883396324515, 942.9009953954736, 951.971870379988, 1003.6687892995294, 1009.6920439265947, 16823.66352672964, 20094.598108037757, 20313.49216269605, 21129.78870211309, 21347.0, 967.1440774914252, 1003.007023047178, 1016.6035246253966, 1034.374203606822, 1034.7620047389144, 8500.44747091437, 8982.955085224663, 10363.322142984922, 10387.740306492398, 10572.788544229072, 483393.0, 583287.0, 586155.0, 588687.0, 606999.0, 150532.0, 153026.0, 153302.46697533104, 160817.19591401969, 164239.0, 1079.2714917430703, 1200.5557943560939, 1260.6407173955379, 1262.5707259531803, 1264.1909178126007, 21593.78406215918, 21880.0, 22644.320670379915, 22758.31725048185, 22876.08495660155, 66503.00245496418, 69005.0, 69457.30542694508, 69929.0, 70227.0, 86324.56837715772, 86478.0, 90003.54998224966, 90990.0, 95175.0, 11235.213535052468, 11390.430478475877, 11397.145214108808, 11519.366434846164, 11523.193376463567, 17392.739108913123, 17909.731354029962, 18268.634627307645, 18398.81601183971, 18451.630967380846, 14603.780943286072, 14760.852391475948, 14814.851851481348, 14819.0, 14852.7772083421, 12583.119181657195, 13089.0837641368, 13148.342582870628, 13154.144980576231, 13572.592822215349, 15146.848531514544, 15212.315445804745, 15245.237738113272, 15383.461578844686, 15394.769078464056, 11135.164862635173, 11174.161937854531, 11201.60794372193, 11268.605598804003, 11288.548458061585, 14294.571162865132, 14455.638609034859, 14480.493182738554, 14528.346224420138, 14545.781813272597], "value": 11402.395890119722, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.6505576022355, 1441.9927900360497, 1468.9779653305195, 1474.0271420862232, 1477.5124209011024, 13082.476700931964, 13490.797638035438, 13588.388522516485, 13611.93194034031, 13625.046246762719, 12930.806037909415, 13188.604341869735, 13306.800397994013, 13317.53388631398, 13337.53318633848, 2708.8645567721633, 2748.010716142197, 2748.0244513197827, 2751.312171957011, 2751.3534319434916, 2659.348459627394, 2702.094798242591, 2702.540568103417, 2703.8377697338124, 2711.5797051457057, 2246.292417888368, 2324.0587562037404, 2659.627652128694, 2713.145359211842, 2717.402171522262, 2267.2631394796676, 2279.886005699708, 2624.960625590618, 2713.4844379567817, 2722.768564672005, 2690.932726681829, 2696.083331667223, 2701.9054333098443, 2702.554133538567, 2708.0386462805814, 13056.543020994219, 13169.078164528486, 13174.934125329311, 13177.143485673401, 13180.736385272337, 7347.559146451176, 7641.770746877547, 7641.808954776119, 7658.119316278644, 7665.271799179069, 4490.259107247301, 4723.858284251478, 4794.544518270759, 4800.711957282574, 4832.299316599108, 1371.5062577472202, 1379.3034517568565, 1379.620604333806, 1399.3003498250876, 1401.628568429367, 19524.902375488175, 19589.9020504898, 19695.0, 19723.802761972485, 19773.703394448952, 6385.96807015962, 6510.934890651033, 6543.476521878239, 6621.735130594819, 6635.601863888086, 13983.230922299372, 14020.509282175075, 14025.438982440588, 14025.929870350583, 14055.929720351538, 7268.236835132235, 7291.161516425573, 7395.519291246052, 7399.223081576357, 7421.1094668639025, 5632.436756324335, 6455.0, 6880.449564034865, 6881.346272104142, 6887.827804304932, 4116.300228961084, 4739.620830333565, 4905.950940490621, 4931.827386041471, 4975.601951843843, 1407.2400903512194, 1428.3857941085225, 1431.1484666623342, 1440.0495672855866, 1460.941562337516, 671.6809515480107, 808.2241048593298, 836.1137194573771, 840.9243168114776, 869.1178453869328, 6101.877962440726, 6652.634105124264, 6883.552569082994, 6963.9303606964295, 7303.89044164338, 664.6012392564422, 685.9245482996866, 696.2584847137782, 698.1099098649263, 708.8440543080617, 4247.957520424818, 5171.5862730981435, 5232.5552328052545, 5331.840044798662, 5389.649672771257, 14733.0, 16131.919340403458, 17566.121693915255, 18129.637407251783, 18318.992455414813, 17663.293468261127, 17701.937883727016, 18468.630627387378, 18517.81482185188, 18531.629367412577, 9044.231240344512, 9254.676086336945, 9509.144177024102, 9524.0, 9538.809223815484, 7770.0, 7968.561729104723, 8041.879371809545, 8132.75601731949, 8391.0, 14104.929475352557, 14147.292635368396, 16356.591085222964, 16405.507834764976, 16442.013479191286, 13656.268746250591, 15430.0, 15994.8400515998, 16742.748858766885, 16742.916285418494, 8676.479411235347, 10440.947795260976, 10682.83975740349, 10841.457927103456, 10926.180536459638, 12507.0, 12672.429740661877, 12677.049221308254, 12696.047796415121, 12717.0, 8613.784655383666, 9394.95302523483, 9762.951185244028, 9841.409515429097, 10235.283530152818, 9355.719328420158, 9665.468399238109, 9784.9021509784, 9834.21326293909, 9947.801043979225, 8748.212660860552, 8869.689560865598, 9015.053419391132, 9044.954775226082, 9279.0, 5937.782754535224, 6099.878002440015, 6621.966890165711, 6954.165500140016, 6962.721491140298, 5827.921834460638, 6916.204636466669, 6993.300669932966, 7129.0, 7181.317774811385], "value": 5926.906055317103, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.24487249862767, 430.504919342756, 462.03204287018696, 465.1534207741909, 470.4425256071556, 6142.20151380321, 6432.195975503062, 6527.934720652792, 6529.216494020712, 6625.635590042546, 6092.908606370896, 6292.370762923705, 6299.047295338448, 6311.526635502344, 6334.714937827797, 4340.370646256291, 4723.90552188956, 4743.928841067387, 4748.8575342739605, 4772.785224664898, 4144.316187829019, 4599.609033232163, 4753.429588449391, 4760.33355330254, 4767.594754445876, 4482.193205223059, 4757.690750101232, 4777.426708794949, 4779.378680771495, 4782.306565547993, 4760.595349395323, 4770.833020844271, 4773.403324584427, 4777.474477807438, 4785.282207668877, 4114.938275925863, 4146.958530414688, 4168.749875007509, 4192.748435093904, 4216.882526130578, 5098.643094983353, 5287.9159772246985, 6132.877342453171, 6238.068107989237, 6253.278250400877, 5729.0, 5730.34101078373, 5734.885302293973, 5740.885182296331, 5785.537157027427, 6660.067590537328, 6966.477514186406, 6967.442604591621, 6973.63030466543, 6988.091548098765, 4702.27114797207, 4817.313940120965, 4823.589994850442, 4828.155072862271, 4838.153323168432, 6712.228093769279, 7242.927570724278, 7336.413086953031, 7343.485955983083, 7358.300961408658, 4683.578477937002, 4876.122297986397, 4894.238074293252, 4914.680545764514, 4918.458969513351, 4980.555638864688, 5002.474740152305, 5007.349044624176, 5008.424031236382, 5008.57427118699, 5021.271915572294, 5699.401562835926, 5705.885882282414, 5713.8285851424525, 5716.79991200306, 5751.59738818287, 6708.846579092157, 6831.6242606656115, 6985.161780586261, 7030.578165309996, 1689.923953422099, 1692.255407620643, 1702.5318037539776, 1704.7272436410115, 1712.5376148439852, 1048.130052056791, 1070.063694267516, 1086.652271273185, 1086.8369744538381, 1136.5056200552785, 6315.8105256841445, 6398.200224971879, 6414.262359828679, 6421.5825971311715, 6511.934880651228, 7148.606826624482, 7176.7298457417755, 7198.424126070006, 7203.0, 7213.206547279795, 5968.731407086691, 6159.84600384985, 6389.297177310492, 6420.935790642034, 6475.244358272191, 2854.6717127530183, 3135.639401468815, 3150.6691797361404, 3323.4017876781927, 3677.7793332400142, 5721.685307308138, 6682.064510968468, 7317.719399105135, 7339.486235963538, 7345.88981165283, 6188.628682278988, 6484.773032943825, 6907.7236910524025, 7313.158986716596, 7380.889286660705, 5771.538276937751, 7254.854902902018, 7295.088113985752, 7341.375983041501, 7343.706251749977, 6276.995680691068, 6438.7102580384935, 7075.93860920866, 7128.394086502705, 7195.229836512875, 6137.3862613738265, 6468.15913931186, 7049.823754406181, 7066.4346852250665, 7072.71709131629, 6102.969485152724, 6619.775341561826, 6811.4550835934, 6855.897161542481, 7326.304001120098, 4845.691663250832, 4869.415670119608, 4888.388951381077, 4890.948446084109, 4893.119238537098, 7104.538205016657, 7126.572405655677, 7129.755961037612, 7130.286971303036, 7150.42796576283, 5258.605604579595, 5854.707264636836, 6248.750049998131, 6420.775272865428, 6809.156797418563, 5745.0987251437755, 6403.775867844603, 6579.309172537006, 6812.659367031728, 6821.454283657395, 5355.625106242525, 6673.366030227121, 6705.295943926013, 6725.865482690221, 6770.4922130839395, 5965.492933100735, 6546.705398256994, 6566.946369938002, 6720.462363011045, 6833.453323734188, 6599.868002640016, 6603.759447322234, 6609.603423794588, 6623.966880165568, 6659.7003134860015], "value": 5026.969168960592, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.8023952095806, 699.6956323999058, 744.3821628048719, 766.6511737159591, 817.9427440079193, 16156.272967716452, 16876.98738075715, 17111.22999465024, 17140.914295428505, 17146.571335716613, 10123.341982771115, 12900.54848080315, 12969.740605187913, 13147.934260328684, 13478.865211347884, 547.8958997790407, 660.3627499463017, 834.8539005674013, 967.2552134856156, 967.7870868408938, 989.5992123190083, 991.4546999150474, 992.4392718114278, 994.5325696922434, 995.2137811129236, 1025.4975062219496, 1036.1400037968506, 1038.890916453773, 1039.365986748083, 1043.519980808829, 950.7100334398006, 1034.6533911139777, 1037.3205550364523, 1038.0, 1041.3543602966133, 776.97669069928, 782.3545574900729, 784.1139512351064, 786.5516655506364, 790.4782843323379, 15921.840781592151, 16345.101019443926, 17599.912000439916, 17631.206595703225, 17647.294108235656, 17104.230309636096, 17481.825181748274, 17643.558911027198, 17707.93752374849, 17717.202725877367, 14364.425422983068, 14513.0, 14785.630359240997, 14848.851511484854, 14969.952103352769, 3658.70730341572, 3697.2975134724584, 3701.796401197933, 3702.37059699849, 3709.1839795245005, 112340.0, 118707.0, 127045.09432358522, 138182.0, 139970.0, 11187.552497900155, 11430.542778288942, 11532.88467115335, 13574.660633484242, 14112.082714623517, 25821.967121314938, 25957.22128336153, 26392.60411093835, 26513.867430663107, 26543.601845972327, 7551.169371369145, 7573.659185336673, 7589.468737188344, 7591.772246832604, 7605.315521602972, 3880.1463677990796, 3919.1573811630637, 3921.9607803922167, 3949.4865667463046, 3975.940360894589, 4558.749268790249, 4679.789409476582, 4708.293755936568, 4709.835155769531, 4718.504557021532, 964.1370972979284, 994.3487016004528, 999.4053538144792, 1003.3377970539409, 1003.5885287032281, 642.9389208025231, 643.9935600644027, 644.7453255963868, 662.9734810607619, 670.4167374384219, 11117.22179447431, 11173.608923687632, 12221.633350999484, 12607.621771346874, 12672.873271267354, 573.438030729887, 611.6605284067297, 627.5481653209682, 634.2864277687602, 671.1308855032744, 5464.480874316933, 5529.861753456115, 5578.9163162552595, 5729.914051289234, 5741.224934633855, 126007.7399225996, 130100.0, 144339.0, 145555.0, 148616.25691871688, 100570.0, 117697.0, 118940.0, 120975.39512302558, 126311.0, 86842.56578717066, 93567.53216233876, 95137.57293640601, 100060.99939000662, 103790.48104759428, 9094.0, 10397.428141452292, 11827.82258266144, 11979.221350612183, 12401.75196496047, 35222.647773522964, 38835.805820970716, 42449.0, 43806.0, 44953.32570011518, 82504.58747706223, 82727.0, 85349.57325213334, 87883.12116879005, 89439.0, 16095.615981972389, 16662.50012499627, 16708.749368759236, 16967.321307147577, 17076.573085672957, 18376.908115459337, 18727.531811704815, 19144.521386965436, 19426.0, 19729.21083156715, 16803.495895123167, 17407.30370785205, 17416.216270267614, 17753.467395978143, 17781.555461113574, 16598.17009149562, 17866.91066544659, 18213.542916566377, 18308.542286442946, 18333.908330458264, 16624.0, 16986.320547177973, 17210.91394543019, 17421.825781742526, 17439.912800435915, 9686.660966866126, 11286.266392684449, 11340.94329528347, 12021.398930053638, 12465.875341246472, 12466.43901024438, 13853.930730346283, 14111.435542578181, 14429.35067921967, 14597.781033284296], "value": 8589.441042768802, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 164.0, 130.0, 121.0, 116.0, 113.0, 114.0, 117.0, 135.0, 173.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 145.0, 145.0, 108.0, 126.0, 112.0, 152.0, 148.0, 112.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 201.0, 204.0, 208.0, 217.0, 217.0, 215.0, 200.0, 198.0, 192.0], "value": 206.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 73.0, 121.0, 57.0, 62.0, 67.0, 81.0, 106.0, 85.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 110.0, 70.0, 76.0, 77.0, 126.0, 71.0, 73.0, 124.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 93.0, 89.0, 89.0, 88.0, 95.0, 88.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 96.0, 97.0, 106.0, 96.0, 95.0, 97.0, 96.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 92.0, 92.0, 91.0, 91.0, 91.0, 95.0, 92.0, 91.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 53.0, 55.0, 55.0, 51.0, 53.0, 51.0, 54.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 63.0, 81.0, 59.0, 65.0, 55.0, 58.0, 56.0, 57.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 81.0, 82.0, 81.0, 83.0, 82.0, 84.0, 81.0, 81.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 139.0, 138.0, 135.0, 137.0, 160.0, 146.0, 166.0, 156.0, 173.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 183.0, 186.0, 187.0, 186.0, 183.0, 184.0, 183.0, 213.0, 188.0], "value": 188.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 92.0, 77.0, 60.0, 91.0, 88.0, 91.0, 101.0, 99.0, 66.0], "value": 82.7, "unit": "score"}]}]} 06:09:04 INFO - Return code: 0 06:09:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:09:04 INFO - # TBPL SUCCESS # 06:09:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:09:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:09:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:09:04 INFO - 2017-06-12 06:09:04.397 screenresolution[1677:12555] starting screenresolution argv=screenresolution get 06:09:04 INFO - 2017-06-12 06:09:04.400 screenresolution[1677:12555] Display 0: 1600x1200x32@60 06:09:04 INFO - 2017-06-12 06:09:04.406 screenresolution[1678:12558] starting screenresolution argv=screenresolution list 06:09:04 INFO - Available Modes on Display 0 06:09:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:09:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:09:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:09:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:09:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:09:04 INFO - Intel Iris: 06:09:04 INFO - Chipset Model: Intel Iris 06:09:04 INFO - Type: GPU 06:09:04 INFO - Bus: Built-In 06:09:04 INFO - VRAM (Dynamic, Max): 1536 MB 06:09:04 INFO - Vendor: Intel (0x8086) 06:09:04 INFO - Device ID: 0x0a2e 06:09:04 INFO - Revision ID: 0x0009 06:09:04 INFO - Displays: 06:09:04 INFO - Display: 06:09:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:09:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:09:04 INFO - Display Serial Number: 1600x1200 60 06:09:04 INFO - Main Display: Yes 06:09:04 INFO - Mirror: Off 06:09:04 INFO - Online: Yes 06:09:04 INFO - Rotation: Supported 06:09:04 INFO - Return code: 0 06:09:04 INFO - Running post-action listener: _resource_record_post_action 06:09:04 INFO - [mozharness: 2017-06-12 13:09:04.650722Z] Finished run-tests step (success) 06:09:04 INFO - Running post-run listener: _resource_record_post_run 06:09:04 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 34872832; Write bytes: 577197568; Read time: 236; Write time: 1782 06:09:04 INFO - TinderboxPrint: CPU usage
53.2% 06:09:04 INFO - TinderboxPrint: I/O read bytes / time
34,872,832 / 236 06:09:04 INFO - TinderboxPrint: I/O write bytes / time
577,197,568 / 1,782 06:09:04 INFO - TinderboxPrint: CPU idle
2,354.1 (46.8%) 06:09:04 INFO - TinderboxPrint: CPU system
1,015.0 (20.2%) 06:09:04 INFO - TinderboxPrint: CPU user
1,656.6 (33.0%) 06:09:04 INFO - TinderboxPrint: Swap in / out
283,406,336 / 0 06:09:04 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247931392; Write bytes: 242550784; Read time: 18045; Write time: 531 06:09:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:09:04 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 32783872; Write bytes: 320249344; Read time: 216; Write time: 1203 06:09:05 INFO - Running post-run listener: _upload_blobber_files 06:09:05 INFO - Blob upload gear active. 06:09:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:09:05 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 06:09:05 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'] 06:09:05 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 06:09:05 INFO - (blobuploader) - INFO - Open directory for files ... 06:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:09:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:06 INFO - (blobuploader) - INFO - Done attempting. 06:09:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:09:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:09:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:07 INFO - (blobuploader) - INFO - Done attempting. 06:09:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:09:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:09:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:08 INFO - (blobuploader) - INFO - Done attempting. 06:09:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:09:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:09:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:10 INFO - (blobuploader) - INFO - Done attempting. 06:09:10 INFO - (blobuploader) - INFO - Iteration through files over. 06:09:10 INFO - Return code: 0 06:09:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:09:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:09:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbecd1ccf2cb233c906491955dfe662fd74cb1023947f0d78ad51effbc2be8d6b75a06061beb248d29c9aac5c2c7db6504d91684be73657a47c009a2ff744aa6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498680cbd1faf15b7b0bf008e8bcbcbb318c787deaacf97625595a23c1440269be7b735dd57c5b8db6eee535395197e2569b5341882ee139fbcdb4fcaf2cb329", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013931d3bed71a648542394a2fd4c7e2778004adcf64fe0fc637e6a651b375909e5c02516f175ca1c90a752e2868a92e07ee8f344e83e4d232f39c6cdff828", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0eeaf2e86f9966e52319f4c6aa6cdea2f6677d937981e2379ecb2a42ef327f155ed0c70a7a7fe0483eaba9c9462962759a50412c9230284ea3d9f72d2201cf2"} 06:09:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:09:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:09:10 INFO - Contents: 06:09:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbecd1ccf2cb233c906491955dfe662fd74cb1023947f0d78ad51effbc2be8d6b75a06061beb248d29c9aac5c2c7db6504d91684be73657a47c009a2ff744aa6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498680cbd1faf15b7b0bf008e8bcbcbb318c787deaacf97625595a23c1440269be7b735dd57c5b8db6eee535395197e2569b5341882ee139fbcdb4fcaf2cb329", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013931d3bed71a648542394a2fd4c7e2778004adcf64fe0fc637e6a651b375909e5c02516f175ca1c90a752e2868a92e07ee8f344e83e4d232f39c6cdff828", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0eeaf2e86f9966e52319f4c6aa6cdea2f6677d937981e2379ecb2a42ef327f155ed0c70a7a7fe0483eaba9c9462962759a50412c9230284ea3d9f72d2201cf2"} 06:09:10 INFO - Running post-run listener: copy_logs_to_upload_dir 06:09:10 INFO - Copying logs to upload dir... 06:09:10 INFO - mkdir: /builds/slave/test/build/upload/logs 06:09:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.333581 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-12 06:09:10.997450) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 06:09:11.001445) ========= 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.zEvPcylVaX/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.EHySayR67k/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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/fbecd1ccf2cb233c906491955dfe662fd74cb1023947f0d78ad51effbc2be8d6b75a06061beb248d29c9aac5c2c7db6504d91684be73657a47c009a2ff744aa6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498680cbd1faf15b7b0bf008e8bcbcbb318c787deaacf97625595a23c1440269be7b735dd57c5b8db6eee535395197e2569b5341882ee139fbcdb4fcaf2cb329", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013931d3bed71a648542394a2fd4c7e2778004adcf64fe0fc637e6a651b375909e5c02516f175ca1c90a752e2868a92e07ee8f344e83e4d232f39c6cdff828", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0eeaf2e86f9966e52319f4c6aa6cdea2f6677d937981e2379ecb2a42ef327f155ed0c70a7a7fe0483eaba9c9462962759a50412c9230284ea3d9f72d2201cf2"} build_url:https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011161 build_url: 'https://queue.taskcluster.net/v1/task/TiYwUT4kTRS-f5Ali8Jcgw/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/fbecd1ccf2cb233c906491955dfe662fd74cb1023947f0d78ad51effbc2be8d6b75a06061beb248d29c9aac5c2c7db6504d91684be73657a47c009a2ff744aa6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/498680cbd1faf15b7b0bf008e8bcbcbb318c787deaacf97625595a23c1440269be7b735dd57c5b8db6eee535395197e2569b5341882ee139fbcdb4fcaf2cb329", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013931d3bed71a648542394a2fd4c7e2778004adcf64fe0fc637e6a651b375909e5c02516f175ca1c90a752e2868a92e07ee8f344e83e4d232f39c6cdff828", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0eeaf2e86f9966e52319f4c6aa6cdea2f6677d937981e2379ecb2a42ef327f155ed0c70a7a7fe0483eaba9c9462962759a50412c9230284ea3d9f72d2201cf2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 06:09:11.034310) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 06:09:11.034642) ========= 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.zEvPcylVaX/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.EHySayR67k/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.005277 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 06:09:11.083864) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 06:09:11.084267) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 06:09:11.084649) ========= ========= Total master_lag: 0.17 =========