builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0318 starttime: 1497090663.78 results: success (0) revision: 91dc9525c422f11041da33b008b14a8117ed9a40 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:03.778505) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:03.779076) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:03.779363) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:03.842292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:03.842594) ========= 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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-10 03:31:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 69.5M=0s 2017-06-10 03:31:04 (69.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.191986 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:04.057437) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:04.057717) ========= 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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.031155 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:04.109634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 03:31:04.109930) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 91dc9525c422f11041da33b008b14a8117ed9a40 --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 91dc9525c422f11041da33b008b14a8117ed9a40 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JeZRSNY2d9/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.kb6R7WjUbH/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-10 03:31:04,213 truncating revision to first 12 chars 2017-06-10 03:31:04,213 Setting DEBUG logging. 2017-06-10 03:31:04,213 attempt 1/10 2017-06-10 03:31:04,213 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/91dc9525c422?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-10 03:31:05,514 unpacking tar archive at: mozilla-central-91dc9525c422/testing/mozharness/ program finished with exit code 0 elapsedTime=1.582382 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 03:31:05.717232) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:05.720624) ========= script_repo_revision: 91dc9525c422f11041da33b008b14a8117ed9a40 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:05.721054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:05.721377) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 03:31:05.744142) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-10 03:31:05.747509) ========= /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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:31:05 INFO - MultiFileLogger online at 20170610 03:31:05 in /builds/slave/test 03:31:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:31:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:31:05 INFO - {'append_to_log': False, 03:31:05 INFO - 'base_work_dir': '/builds/slave/test', 03:31:05 INFO - 'blob_upload_branch': 'Firefox', 03:31:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:31:05 INFO - 'branch': 'Firefox', 03:31:05 INFO - 'buildbot_json_path': 'buildprops.json', 03:31:05 INFO - 'config_files': ('talos/mac_config.py',), 03:31:05 INFO - 'default_actions': ('clobber', 03:31:05 INFO - 'read-buildbot-config', 03:31:05 INFO - 'download-and-extract', 03:31:05 INFO - 'populate-webroot', 03:31:05 INFO - 'create-virtualenv', 03:31:05 INFO - 'install', 03:31:05 INFO - 'setup-mitmproxy', 03:31:05 INFO - 'run-tests'), 03:31:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:31:05 INFO - 'download_minidump_stackwalk': True, 03:31:05 INFO - 'download_symbols': 'ondemand', 03:31:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:31:05 INFO - 'tooltool.py': '/tools/tooltool.py', 03:31:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:31:05 INFO - '/tools/misc-python/virtualenv.py')}, 03:31:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:31:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:31:05 INFO - 'gecko_profile': False, 03:31:05 INFO - 'gecko_profile_interval': 0, 03:31:05 INFO - 'installer_path': 'installer.exe', 03:31:05 INFO - 'log_level': 'info', 03:31:05 INFO - 'log_name': 'talos', 03:31:05 INFO - 'log_to_console': True, 03:31:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:31:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:31:05 INFO - 'opt_config_files': (), 03:31:05 INFO - 'pip_index': False, 03:31:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:31:05 INFO - 'cmd': ('bash', 03:31:05 INFO - '-c', 03:31:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:31:05 INFO - 'enabled': True, 03:31:05 INFO - 'halt_on_failure': False, 03:31:05 INFO - 'name': 'check_screen_resolution'},), 03:31:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:31:05 INFO - 'cmd': ('bash', 03:31:05 INFO - '-c', 03:31:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:31:05 INFO - 'enabled': True, 03:31:05 INFO - 'halt_on_failure': False, 03:31:05 INFO - 'name': 'check_screen_resolution'},), 03:31:05 INFO - 'run_cmd_checks_enabled': True, 03:31:05 INFO - 'suite': 'dromaeojs-e10s', 03:31:05 INFO - 'system_bits': '32', 03:31:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:31:05 INFO - 'title': 't-yosemite-r7-0318', 03:31:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:31:05 INFO - 'use_talos_json': True, 03:31:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:31:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:31:05 INFO - 'work_dir': 'build'} 03:31:05 INFO - [mozharness: 2017-06-10 10:31:05.956386Z] Running clobber step. 03:31:05 INFO - Running pre-action listener: _resource_record_pre_action 03:31:05 INFO - Running main action method: clobber 03:31:05 INFO - rmtree: /builds/slave/test/build 03:31:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:31:08 INFO - Running post-action listener: _resource_record_post_action 03:31:08 INFO - [mozharness: 2017-06-10 10:31:08.336767Z] Finished clobber step (success) 03:31:08 INFO - [mozharness: 2017-06-10 10:31:08.337027Z] Running read-buildbot-config step. 03:31:08 INFO - Running pre-action listener: _resource_record_pre_action 03:31:08 INFO - Running main action method: read_buildbot_config 03:31:08 INFO - Using buildbot properties: 03:31:08 INFO - { 03:31:08 INFO - "project": "", 03:31:08 INFO - "product": "firefox", 03:31:08 INFO - "who": "archaeopteryx@coole-files.de", 03:31:08 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:31:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:31:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:31:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:31:08 INFO - "stage_platform": "macosx64", 03:31:08 INFO - "basedir": "/builds/slave/test", 03:31:08 INFO - "buildnumber": 21, 03:31:08 INFO - "platform": "yosemite_r7", 03:31:08 INFO - "parent_task_id": "CNckeSrRQieOuwrEnGgyfQ", 03:31:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:31:08 INFO - "slavebuilddir": "test", 03:31:08 INFO - "taskId": "C56JOP3tS9GM7UWUqWquug", 03:31:08 INFO - "branch": "mozilla-central", 03:31:08 INFO - "script_repo_revision": "production", 03:31:08 INFO - "revision": "91dc9525c422f11041da33b008b14a8117ed9a40", 03:31:08 INFO - "slavename": "t-yosemite-r7-0318", 03:31:08 INFO - "repo_path": "mozilla-central" 03:31:08 INFO - } 03:31:08 INFO - Finding installer, test and symbols from parent task. 03:31:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C56JOP3tS9GM7UWUqWquug'}, attempt #1 03:31:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C56JOP3tS9GM7UWUqWquug'}, attempt #1 03:31:09 INFO - Task dependencies: UaTkNuVxTZmp7OU4-pVDFQ CNckeSrRQieOuwrEnGgyfQ 03:31:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ'}, attempt #1 03:31:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:31:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/test_packages.json 03:31:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/target.crashreporter-symbols.zip 03:31:09 INFO - Running post-action listener: _resource_record_post_action 03:31:09 INFO - [mozharness: 2017-06-10 10:31:09.724303Z] Finished read-buildbot-config step (success) 03:31:09 INFO - [mozharness: 2017-06-10 10:31:09.724428Z] Running download-and-extract step. 03:31:09 INFO - Running pre-action listener: _resource_record_pre_action 03:31:09 INFO - Running main action method: download_and_extract 03:31:09 INFO - mkdir: /builds/slave/test/build/tests 03:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:09 INFO - trying https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:31:09 INFO - Downloading https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:31:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 03:31:10 INFO - Downloaded 1328 bytes. 03:31:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:31:10 INFO - Using the following test package requirements: 03:31:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:31:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:31:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:31:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:31:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'jsshell-mac.zip'], 03:31:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:31:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:31:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:31:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:31:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 03:31:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:31:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:31:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:31:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:31:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:31:10 INFO - Fetch https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:31:12 INFO - Content-Length response header: 59540391 03:31:12 INFO - Bytes received: 59540391 03:31:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:31:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:31:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:31:30 INFO - Fetch https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:31:32 INFO - Content-Length response header: 59540391 03:31:32 INFO - Bytes received: 59540391 03:31:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:31:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:31:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:31:51 INFO - Content-Length response header: 14450010 03:31:51 INFO - Bytes received: 14450010 03:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:51 INFO - trying https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:31:51 INFO - Downloading https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:31:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 03:31:53 INFO - Downloaded 69235962 bytes. 03:31:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:31:53 INFO - mkdir: /builds/slave/test/properties 03:31:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:31:53 INFO - Writing to file /builds/slave/test/properties/build_url 03:31:53 INFO - Contents: 03:31:53 INFO - build_url:https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:31:53 INFO - Running post-action listener: _resource_record_post_action 03:31:53 INFO - Running post-action listener: set_extra_try_arguments 03:31:53 INFO - [mozharness: 2017-06-10 10:31:53.526168Z] Finished download-and-extract step (success) 03:31:53 INFO - [mozharness: 2017-06-10 10:31:53.526323Z] Running populate-webroot step. 03:31:53 INFO - Running pre-action listener: _resource_record_pre_action 03:31:53 INFO - Running main action method: populate_webroot 03:31:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:31:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:31:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:31:53 INFO - '--tppagecycles', 03:31:53 INFO - '7'], 03:31:53 INFO - 'tests': ['tsvgm']}}, 03:31:53 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:31:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:31:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:31:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['damp', 'tps']}, 03:31:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'tests': ['damp', 'tps']}, 03:31:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['dromaeo_dom']}, 03:31:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:31:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:31:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:31:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:31:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['a11yr', 03:31:53 INFO - 'ts_paint', 03:31:53 INFO - 'tpaint', 03:31:53 INFO - 'sessionrestore', 03:31:53 INFO - 'sessionrestore_no_auto_restore', 03:31:53 INFO - 'tabpaint']}, 03:31:53 INFO - 'other-e10s': {'tests': ['a11yr', 03:31:53 INFO - 'ts_paint', 03:31:53 INFO - 'tpaint', 03:31:53 INFO - 'sessionrestore', 03:31:53 INFO - 'sessionrestore_no_auto_restore', 03:31:53 INFO - 'tabpaint']}, 03:31:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:31:53 INFO - 'ts_paint', 03:31:53 INFO - 'tpaint', 03:31:53 INFO - 'sessionrestore', 03:31:53 INFO - 'sessionrestore_no_auto_restore', 03:31:53 INFO - 'tabpaint']}, 03:31:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:31:53 INFO - 'ts_paint', 03:31:53 INFO - 'tpaint', 03:31:53 INFO - 'sessionrestore', 03:31:53 INFO - 'sessionrestore_no_auto_restore', 03:31:53 INFO - 'tabpaint']}, 03:31:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['a11yr', 03:31:53 INFO - 'ts_paint', 03:31:53 INFO - 'tpaint', 03:31:53 INFO - 'sessionrestore', 03:31:53 INFO - 'sessionrestore_no_auto_restore', 03:31:53 INFO - 'tabpaint']}, 03:31:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['a11yr', 03:31:53 INFO - 'ts_paint', 03:31:53 INFO - 'tpaint', 03:31:53 INFO - 'sessionrestore', 03:31:53 INFO - 'sessionrestore_no_auto_restore', 03:31:53 INFO - 'tabpaint']}, 03:31:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:31:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:31:53 INFO - 'bloom_basic_ref']}, 03:31:53 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:31:53 INFO - 'talos_options': ['--mitmproxy', 03:31:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:31:53 INFO - '--firstNonBlankPaint'], 03:31:53 INFO - 'tests': ['quantum_pageload_google', 03:31:53 INFO - 'quantum_pageload_youtube', 03:31:53 INFO - 'quantum_pageload_amazon', 03:31:53 INFO - 'quantum_pageload_facebook']}, 03:31:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['tsvgx', 03:31:53 INFO - 'tsvgr_opacity', 03:31:53 INFO - 'tart', 03:31:53 INFO - 'tscrollx', 03:31:53 INFO - 'cart', 03:31:53 INFO - 'tsvg_static']}, 03:31:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:31:53 INFO - 'tsvgr_opacity', 03:31:53 INFO - 'tart', 03:31:53 INFO - 'tscrollx', 03:31:53 INFO - 'cart', 03:31:53 INFO - 'tsvg_static']}, 03:31:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'talos_options': ['--disable-e10s'], 03:31:53 INFO - 'tests': ['tp5o']}, 03:31:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:31:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'talos_options': ['--disable-e10s', 03:31:53 INFO - '--xperf_path', 03:31:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:53 INFO - 'tests': ['tp5n']}, 03:31:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:31:53 INFO - 'talos_options': ['--xperf_path', 03:31:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:53 INFO - 'tests': ['tp5n']}}, 03:31:53 INFO - 'talos.zip': {'path': '', 03:31:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:31:53 INFO - Running post-action listener: _resource_record_post_action 03:31:53 INFO - [mozharness: 2017-06-10 10:31:53.549221Z] Finished populate-webroot step (success) 03:31:53 INFO - [mozharness: 2017-06-10 10:31:53.549326Z] Running create-virtualenv step. 03:31:53 INFO - Running pre-action listener: _resource_record_pre_action 03:31:53 INFO - Running main action method: create_virtualenv 03:31:53 INFO - Creating virtualenv /builds/slave/test/build/venv 03:31:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:31:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:31:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:31:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:31:53 INFO - Using real prefix '/tools/python27' 03:31:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:31:54 INFO - Installing distribute.............................................................................................................................................................................................done. 03:31:57 INFO - Installing pip.................done. 03:31:57 INFO - Return code: 0 03:31:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:31:57 INFO - Reading from file tmpfile_stdout 03:31:57 INFO - Output received: 03:31:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:31:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31: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')]} 03:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:57 INFO - 'HOME': '/Users/cltbld', 03:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:57 INFO - 'LOGNAME': 'cltbld', 03:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:57 INFO - 'MOZ_NO_REMOTE': '1', 03:31:57 INFO - 'NO_EM_RESTART': '1', 03:31:57 INFO - 'PAGER': '/bin/cat', 03:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:57 INFO - 'PWD': '/builds/slave/test', 03:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:57 INFO - 'SHELL': '/bin/bash', 03:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:31:57 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:57 INFO - 'USER': 'cltbld', 03:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:57 INFO - 'XPC_FLAGS': '0x0', 03:31:57 INFO - 'XPC_SERVICE_NAME': '0', 03:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:31:57 INFO - Cleaning up... 03:31:57 INFO - Return code: 0 03:31:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31: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')]} 03:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:57 INFO - 'HOME': '/Users/cltbld', 03:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:57 INFO - 'LOGNAME': 'cltbld', 03:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:57 INFO - 'MOZ_NO_REMOTE': '1', 03:31:57 INFO - 'NO_EM_RESTART': '1', 03:31:57 INFO - 'PAGER': '/bin/cat', 03:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:57 INFO - 'PWD': '/builds/slave/test', 03:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:57 INFO - 'SHELL': '/bin/bash', 03:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:31:57 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:57 INFO - 'USER': 'cltbld', 03:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:57 INFO - 'XPC_FLAGS': '0x0', 03:31:57 INFO - 'XPC_SERVICE_NAME': '0', 03:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:58 INFO - Downloading/unpacking psutil>=3.1.1 03:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:32:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:00 INFO - Installing collected packages: psutil 03:32:00 INFO - Running setup.py install for psutil 03:32:00 INFO - building 'psutil._psutil_osx' extension 03:32:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:32:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:32:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:32:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:32:01 INFO - building 'psutil._psutil_posix' extension 03:32:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:32:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:32:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:32:01 INFO - ^ 03:32:01 INFO - 1 warning generated. 03:32:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:32:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:01 INFO - Successfully installed psutil 03:32:01 INFO - Cleaning up... 03:32:01 INFO - Return code: 0 03:32:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32: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')]} 03:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:01 INFO - 'HOME': '/Users/cltbld', 03:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:01 INFO - 'LOGNAME': 'cltbld', 03:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:01 INFO - 'MOZ_NO_REMOTE': '1', 03:32:01 INFO - 'NO_EM_RESTART': '1', 03:32:01 INFO - 'PAGER': '/bin/cat', 03:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:01 INFO - 'PWD': '/builds/slave/test', 03:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:01 INFO - 'SHELL': '/bin/bash', 03:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:01 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:01 INFO - 'USER': 'cltbld', 03:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:01 INFO - 'XPC_FLAGS': '0x0', 03:32:01 INFO - 'XPC_SERVICE_NAME': '0', 03:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:32:03 INFO - Installing collected packages: mozsystemmonitor 03:32:03 INFO - Running setup.py install for mozsystemmonitor 03:32:03 INFO - Successfully installed mozsystemmonitor 03:32:03 INFO - Cleaning up... 03:32:03 INFO - Return code: 0 03:32:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:32:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:03 INFO - 'HOME': '/Users/cltbld', 03:32:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:03 INFO - 'LOGNAME': 'cltbld', 03:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:03 INFO - 'MOZ_NO_REMOTE': '1', 03:32:03 INFO - 'NO_EM_RESTART': '1', 03:32:03 INFO - 'PAGER': '/bin/cat', 03:32:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:03 INFO - 'PWD': '/builds/slave/test', 03:32:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:03 INFO - 'SHELL': '/bin/bash', 03:32:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:03 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:03 INFO - 'USER': 'cltbld', 03:32:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:03 INFO - 'XPC_FLAGS': '0x0', 03:32:03 INFO - 'XPC_SERVICE_NAME': '0', 03:32:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:03 INFO - Downloading/unpacking jsonschema==2.5.1 03:32: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 03:32: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 03:32: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 03:32: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 03:32:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:32:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:32: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 03:32: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 03:32: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 03:32: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 03:32:05 INFO - Downloading functools32-3.2.3-2.tar.gz 03:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:32:05 INFO - warning: no files found matching '*.txt' 03:32:05 INFO - no previously-included directories found matching 'build' 03:32:05 INFO - no previously-included directories found matching 'dist' 03:32:05 INFO - no previously-included directories found matching '.git*' 03:32:05 INFO - Installing collected packages: jsonschema, functools32 03:32:05 INFO - Running setup.py install for functools32 03:32:05 INFO - warning: no files found matching '*.txt' 03:32:05 INFO - no previously-included directories found matching 'build' 03:32:05 INFO - no previously-included directories found matching 'dist' 03:32:05 INFO - no previously-included directories found matching '.git*' 03:32:05 INFO - Successfully installed jsonschema functools32 03:32:05 INFO - Cleaning up... 03:32:05 INFO - Return code: 0 03:32:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32: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')]} 03:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:05 INFO - 'HOME': '/Users/cltbld', 03:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:05 INFO - 'LOGNAME': 'cltbld', 03:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:05 INFO - 'MOZ_NO_REMOTE': '1', 03:32:05 INFO - 'NO_EM_RESTART': '1', 03:32:05 INFO - 'PAGER': '/bin/cat', 03:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:05 INFO - 'PWD': '/builds/slave/test', 03:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:05 INFO - 'SHELL': '/bin/bash', 03:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:05 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:05 INFO - 'USER': 'cltbld', 03:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:05 INFO - 'XPC_FLAGS': '0x0', 03:32:05 INFO - 'XPC_SERVICE_NAME': '0', 03:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:32:06 INFO - Cleaning up... 03:32:06 INFO - Return code: 0 03:32:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:06 INFO - 'HOME': '/Users/cltbld', 03:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:06 INFO - 'LOGNAME': 'cltbld', 03:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:06 INFO - 'MOZ_NO_REMOTE': '1', 03:32:06 INFO - 'NO_EM_RESTART': '1', 03:32:06 INFO - 'PAGER': '/bin/cat', 03:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:06 INFO - 'PWD': '/builds/slave/test', 03:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:06 INFO - 'SHELL': '/bin/bash', 03:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:06 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:06 INFO - 'USER': 'cltbld', 03:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:06 INFO - 'XPC_FLAGS': '0x0', 03:32:06 INFO - 'XPC_SERVICE_NAME': '0', 03:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:06 INFO - Downloading/unpacking blobuploader==1.2.4 03:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:07 INFO - Downloading blobuploader-1.2.4.tar.gz 03:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:32:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:32:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:08 INFO - Downloading docopt-0.6.1.tar.gz 03:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:32:08 INFO - Installing collected packages: blobuploader, requests, docopt 03:32:08 INFO - Running setup.py install for blobuploader 03:32:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:32:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:32:09 INFO - Running setup.py install for requests 03:32:09 INFO - Running setup.py install for docopt 03:32:09 INFO - Successfully installed blobuploader requests docopt 03:32:09 INFO - Cleaning up... 03:32:09 INFO - Return code: 0 03:32:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:09 INFO - 'HOME': '/Users/cltbld', 03:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:09 INFO - 'LOGNAME': 'cltbld', 03:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:09 INFO - 'MOZ_NO_REMOTE': '1', 03:32:09 INFO - 'NO_EM_RESTART': '1', 03:32:09 INFO - 'PAGER': '/bin/cat', 03:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:09 INFO - 'PWD': '/builds/slave/test', 03:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:09 INFO - 'SHELL': '/bin/bash', 03:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:09 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:09 INFO - 'USER': 'cltbld', 03:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:09 INFO - 'XPC_FLAGS': '0x0', 03:32:09 INFO - 'XPC_SERVICE_NAME': '0', 03:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-l7Sjhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-teeioF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-w08el6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-enRmPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-hrcQsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-GI4oRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-7VzagR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-YTjmN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-iwJF1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-UsxArP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:11 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-7NjZlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:11 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-s5Q0zO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:11 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-bA8RyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:11 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JbFiQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:11 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-QDguX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:11 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ptJSJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:11 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-CtEQvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:32:11 INFO - Running setup.py install for manifestparser 03:32:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:32:11 INFO - Running setup.py install for mozcrash 03:32:12 INFO - Running setup.py install for mozdebug 03:32:12 INFO - Running setup.py install for mozdevice 03:32:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:32:12 INFO - Running setup.py install for mozfile 03:32:12 INFO - Running setup.py install for mozhttpd 03:32:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:32:12 INFO - Running setup.py install for mozinfo 03:32:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:32:12 INFO - Running setup.py install for mozInstall 03:32:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:32:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:32:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:32:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:32:12 INFO - Running setup.py install for mozleak 03:32:13 INFO - Running setup.py install for mozlog 03:32:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:32:13 INFO - Running setup.py install for moznetwork 03:32:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:32:13 INFO - Running setup.py install for mozprocess 03:32:13 INFO - Running setup.py install for mozprofile 03:32:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:32:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:32:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:32:13 INFO - Running setup.py install for mozrunner 03:32:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:32:13 INFO - Running setup.py install for mozscreenshot 03:32:14 INFO - Running setup.py install for moztest 03:32:14 INFO - Running setup.py install for mozversion 03:32:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:32:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:32:14 INFO - Cleaning up... 03:32:14 INFO - Return code: 0 03:32:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:14 INFO - 'HOME': '/Users/cltbld', 03:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:14 INFO - 'LOGNAME': 'cltbld', 03:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:14 INFO - 'MOZ_NO_REMOTE': '1', 03:32:14 INFO - 'NO_EM_RESTART': '1', 03:32:14 INFO - 'PAGER': '/bin/cat', 03:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:14 INFO - 'PWD': '/builds/slave/test', 03:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:14 INFO - 'SHELL': '/bin/bash', 03:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:14 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:14 INFO - 'USER': 'cltbld', 03:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:14 INFO - 'XPC_FLAGS': '0x0', 03:32:14 INFO - 'XPC_SERVICE_NAME': '0', 03:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-W0zuIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Q782Er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-rUAq_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JJpMdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-6awjxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-yVnKPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-81nKeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JUAP4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-tRt1In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-mwIJ_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_kA5eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wESppK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-MP_bh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-xBVznT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:16 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-EyRSD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:16 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-SDlbwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:16 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-xNxPwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:32:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:17 INFO - Downloading blessings-1.6.tar.gz 03:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:32:17 INFO - Installing collected packages: blessings 03:32:17 INFO - Running setup.py install for blessings 03:32:17 INFO - Successfully installed blessings 03:32:17 INFO - Cleaning up... 03:32:18 INFO - Return code: 0 03:32:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:32:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:18 INFO - Reading from file tmpfile_stdout 03:32:18 INFO - Current package versions: 03:32:18 INFO - blessings == 1.6 03:32:18 INFO - blobuploader == 1.2.4 03:32:18 INFO - docopt == 0.6.1 03:32:18 INFO - functools32 == 3.2.3-2 03:32:18 INFO - jsonschema == 2.5.1 03:32:18 INFO - manifestparser == 1.1 03:32:18 INFO - mozInstall == 1.13 03:32:18 INFO - mozcrash == 1.0 03:32:18 INFO - mozdebug == 0.1 03:32:18 INFO - mozdevice == 0.51 03:32:18 INFO - mozfile == 1.2 03:32:18 INFO - mozhttpd == 0.7 03:32:18 INFO - mozinfo == 0.10 03:32:18 INFO - mozleak == 0.1 03:32:18 INFO - mozlog == 3.4 03:32:18 INFO - moznetwork == 0.27 03:32:18 INFO - mozprocess == 0.25 03:32:18 INFO - mozprofile == 0.28 03:32:18 INFO - mozrunner == 6.13 03:32:18 INFO - mozscreenshot == 0.1 03:32:18 INFO - mozsystemmonitor == 0.3 03:32:18 INFO - moztest == 0.8 03:32:18 INFO - mozversion == 1.4 03:32:18 INFO - psutil == 3.1.1 03:32:18 INFO - requests == 1.2.3 03:32:18 INFO - wsgiref == 0.1.2 03:32:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:32:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:18 INFO - 'HOME': '/Users/cltbld', 03:32:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:18 INFO - 'LOGNAME': 'cltbld', 03:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:18 INFO - 'MOZ_NO_REMOTE': '1', 03:32:18 INFO - 'NO_EM_RESTART': '1', 03:32:18 INFO - 'PAGER': '/bin/cat', 03:32:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:18 INFO - 'PWD': '/builds/slave/test', 03:32:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:18 INFO - 'SHELL': '/bin/bash', 03:32:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:18 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:18 INFO - 'USER': 'cltbld', 03:32:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:18 INFO - 'XPC_FLAGS': '0x0', 03:32:18 INFO - 'XPC_SERVICE_NAME': '0', 03:32:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:32:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:32:18 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:32:19 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:32:20 INFO - Installing collected packages: marionette-driver 03:32:20 INFO - Running setup.py install for marionette-driver 03:32:20 INFO - Successfully installed marionette-driver 03:32:20 INFO - Cleaning up... 03:32:20 INFO - Return code: 0 03:32:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100abfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ac3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903d714e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, '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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:32:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:32:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:20 INFO - 'HOME': '/Users/cltbld', 03:32:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:20 INFO - 'LOGNAME': 'cltbld', 03:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:20 INFO - 'MOZ_NO_REMOTE': '1', 03:32:20 INFO - 'NO_EM_RESTART': '1', 03:32:20 INFO - 'PAGER': '/bin/cat', 03:32:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:20 INFO - 'PWD': '/builds/slave/test', 03:32:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:20 INFO - 'SHELL': '/bin/bash', 03:32:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:20 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:20 INFO - 'USER': 'cltbld', 03:32:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:20 INFO - 'XPC_FLAGS': '0x0', 03:32:20 INFO - 'XPC_SERVICE_NAME': '0', 03:32:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:32:20 INFO - Cleaning up... 03:32:20 INFO - Return code: 0 03:32:20 INFO - Running post-action listener: _resource_record_post_action 03:32:20 INFO - Running post-action listener: _start_resource_monitoring 03:32:20 INFO - Starting resource monitoring. 03:32:20 INFO - [mozharness: 2017-06-10 10:32:20.577550Z] Finished create-virtualenv step (success) 03:32:20 INFO - [mozharness: 2017-06-10 10:32:20.578585Z] Running install step. 03:32:20 INFO - Running pre-action listener: _resource_record_pre_action 03:32:20 INFO - Running main action method: install 03:32:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:20 INFO - Reading from file tmpfile_stdout 03:32:20 INFO - Detecting whether we're running mozinstall >=1.0... 03:32:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:32:20 INFO - Reading from file tmpfile_stdout 03:32:20 INFO - Output received: 03:32:20 INFO - Usage: mozinstall [options] installer 03:32:20 INFO - Options: 03:32:20 INFO - -h, --help show this help message and exit 03:32:20 INFO - -d DEST, --destination=DEST 03:32:20 INFO - Directory to install application into. [default: 03:32:20 INFO - "/builds/slave/test"] 03:32:20 INFO - --app=APP Application being installed. [default: firefox] 03:32:20 INFO - mkdir: /builds/slave/test/build/application 03:32:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:32:42 INFO - Reading from file tmpfile_stdout 03:32:42 INFO - Output received: 03:32:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:32:42 INFO - Running post-action listener: _resource_record_post_action 03:32:42 INFO - [mozharness: 2017-06-10 10:32:42.766420Z] Finished install step (success) 03:32:42 INFO - [mozharness: 2017-06-10 10:32:42.766523Z] Running setup-mitmproxy step. 03:32:42 INFO - Running pre-action listener: _resource_record_pre_action 03:32:42 INFO - Running main action method: setup_mitmproxy 03:32:42 INFO - Skipping: mitmproxy is not required 03:32:42 INFO - Running post-action listener: _resource_record_post_action 03:32:42 INFO - [mozharness: 2017-06-10 10:32:42.767067Z] Finished setup-mitmproxy step (success) 03:32:42 INFO - [mozharness: 2017-06-10 10:32:42.767165Z] Running run-tests step. 03:32:42 INFO - Running pre-action listener: _resource_record_pre_action 03:32:42 INFO - Running main action method: run_tests 03:32:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:32:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:32:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:32:42 INFO - 2017-06-10 03:32:42.779 screenresolution[1634:8115] starting screenresolution argv=screenresolution get 03:32:42 INFO - 2017-06-10 03:32:42.782 screenresolution[1634:8115] Display 0: 1600x1200x32@60 03:32:42 INFO - 2017-06-10 03:32:42.787 screenresolution[1635:8117] starting screenresolution argv=screenresolution list 03:32:42 INFO - Available Modes on Display 0 03:32:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:32:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:32:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:32:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:32:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:32:43 INFO - Intel Iris: 03:32:43 INFO - Chipset Model: Intel Iris 03:32:43 INFO - Type: GPU 03:32:43 INFO - Bus: Built-In 03:32:43 INFO - VRAM (Dynamic, Max): 1536 MB 03:32:43 INFO - Vendor: Intel (0x8086) 03:32:43 INFO - Device ID: 0x0a2e 03:32:43 INFO - Revision ID: 0x0009 03:32:43 INFO - Displays: 03:32:43 INFO - Display: 03:32:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:32:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:32:43 INFO - Display Serial Number: 1600x1200 60 03:32:43 INFO - Main Display: Yes 03:32:43 INFO - Mirror: Off 03:32:43 INFO - Online: Yes 03:32:43 INFO - Rotation: Supported 03:32:43 INFO - Return code: 0 03:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:32:43 INFO - Python 2.7.3 03:32:43 INFO - Return code: 0 03:32:43 INFO - grabbing minidump binary from tooltool 03:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100815d30>, '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 0x1007dfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100806be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:32:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:32:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:32:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:32:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:32:43 INFO - Return code: 0 03:32:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:32:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:32:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:32:43 INFO - ENV: RUST_BACKTRACE is now 1 03:32:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:32:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0318 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:32:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JeZRSNY2d9/Render', 03:32:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:43 INFO - 'HOME': '/Users/cltbld', 03:32:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:43 INFO - 'LOGNAME': 'cltbld', 03:32:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:32:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:43 INFO - 'MOZ_NO_REMOTE': '1', 03:32:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:32:43 INFO - 'NO_EM_RESTART': '1', 03:32:43 INFO - 'PAGER': '/bin/cat', 03:32:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:43 INFO - 'PWD': '/builds/slave/test', 03:32:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:32:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:43 INFO - 'RUST_BACKTRACE': '1', 03:32:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:32:43 INFO - 'SHELL': '/bin/bash', 03:32:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kb6R7WjUbH/Listeners', 03:32:43 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 03:32:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:43 INFO - 'USER': 'cltbld', 03:32:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:43 INFO - 'XPC_FLAGS': '0x0', 03:32:43 INFO - 'XPC_SERVICE_NAME': '0', 03:32:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:32:43 INFO - mozversion application_buildid: 20170610022200 03:32:43 INFO - mozversion application_changeset: 91dc9525c422f11041da33b008b14a8117ed9a40 03:32:43 INFO - mozversion application_display_name: Nightly 03:32:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:32:43 INFO - mozversion application_name: Firefox 03:32:43 INFO - mozversion application_remotingname: firefox 03:32:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:32:43 INFO - mozversion application_vendor: Mozilla 03:32:43 INFO - mozversion application_version: 55.0a1 03:32:43 INFO - mozversion platform_buildid: 20170610022200 03:32:43 INFO - mozversion platform_changeset: 91dc9525c422f11041da33b008b14a8117ed9a40 03:32:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:32:43 INFO - mozversion platform_version: 55.0a1 03:32:43 INFO - using testdate: 1497090763 03:32:43 INFO - actual date: 1497090763 03:32:43 INFO - starting webserver on 'localhost:49228' 03:32:43 INFO - SUITE-START | Running 2 tests 03:32:43 INFO - TEST-START | dromaeo_css 03:32:43 INFO - operating with platform_type : mac_ 03:32:43 INFO - Initialising browser for dromaeo_css test... 03:32:43 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpsnYHQE/profile 03:32:43 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 03:33:17 INFO - TEST-INFO | 1645: killed by SIGTERM 03:33:17 INFO - Browser initialized. 03:33:17 INFO - Running cycle 1/1 for dromaeo_css test... 03:33:17 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpsnYHQE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:33:19 INFO - PID 1653 | 2017-06-10 03:33:19.280 plugin-container[1654:8678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 03:33:19 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:33:19 INFO - PID 1653 | 2017-06-10 03:33:19.280 plugin-container[1654:8678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 03:33:19 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:33:19 INFO - PID 1653 | 2017-06-10 03:33:19.281 plugin-container[1654:8678] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:33:20 INFO - PID 1653 | 2017-06-10 03:33:20.051 plugin-container[1655:8724] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 03:33:20 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:33:20 INFO - PID 1653 | 2017-06-10 03:33:20.052 plugin-container[1655:8724] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 03:33:20 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:33:20 INFO - PID 1653 | 2017-06-10 03:33:20.052 plugin-container[1655:8724] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:33:29 INFO - PID 1653 | RSS: Main: 253079552 03:33:29 INFO - PID 1653 | 03:36:31 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 03:36:33 INFO - PID 1653 | RSS: Main: 263499776 03:36:33 INFO - PID 1653 | 03:39:35 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 03:39:35 INFO - PID 1653 | RSS: Main: 275963904 03:39:35 INFO - PID 1653 | 03:42:37 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 03:42:38 INFO - PID 1653 | RSS: Main: 275652608 03:42:38 INFO - PID 1653 | 03:45:39 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 03:45:40 INFO - PID 1653 | RSS: Main: 278536192 03:45:40 INFO - PID 1653 | 03:48:43 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 03:48:44 INFO - PID 1653 | RSS: Main: 278413312 03:48:44 INFO - PID 1653 | 03:51:45 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 03:51:46 INFO - PID 1653 | RSS: Main: 279187456 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | __start_tp_report 03:51:46 INFO - PID 1653 | _x_x_mozilla_page_load 03:51:46 INFO - PID 1653 | _x_x_mozilla_page_load_details 03:51:46 INFO - PID 1653 | |i|pagename|runs| 03:51:46 INFO - PID 1653 | |0;dojo.html;3521.31334389794;3595.370810108232;3598.6041535431114;3614.078410005449;3616.8914932552016;4868.391451068612;6131.713108457295;6297.748090076398;6307.716152773125;6362.331955144713;5415.431379705134;6135.956887329154;6196.938030619693;6294.905576416358;6296.181496405461;5442.591805614575;5463.972680136613;5480.534154596864;5500.312460942382;5574.526165275956;5360.303160589118;5403.2705584745945;5576.247206627114;5648.943510564883;5667.801626943058;5529.889402211933;5552.500274975251;5558.610897237195;5597.104463285875;5630.324361076677;5288.735563221823;5364.785408583652;5507.03626865301;5519.806806761784;5537.280153580049;4597.873520987379;5494.587905907033;5508.6970216638065;5527.613067085305;5528.861778455571;5254.632175747699;6191.535634827406;6292.779752708633;6305.621662700207;6320.873582528369;5696.829095127153;6436.774712885073;6510.7721229756735;6685.264620891698;6795.38841504262;6965.582065076112;6969.0940177776565;6977.965110174468;6982.825429364256;6994.4404447644065;6403.455706264973;6411.551191416603;6413.743450262031;6414.262359828586;6432.839179020562;6602.339766023455;6714;6967.721291148398;7068.646567671596;7083.256258092929;5608.270924779753;6355.269144048401;6696.531242813054;6707.496937729592;6721.126253587003;6429.9678501607195;6814.182298124257;6902.240753517109;6924.169099708067;6927.376536111774;6311.179546658905;6347.936520634735;6770.966145169243;6794.626295553693;6807.353301436356;6445.903311450332;6480.611163330215;6721.529492935448;6834.419074378634;6849.2465828758795;5361.919571206435;5414.864628384322;5417.539509141767;5429.945700542944;5456.536194423439;4836.032793441326;4849.854504364875;4864.82973095947;4868.269759536098;4872.731999740048;6231.688415579203;6260.624362538262;6315.5894866833505;6335.588186767845;6404.615723056539;6914.896276555855;6938.757143499954;6981.092457980531;6985.720571177198;6988.93011069893;6384.042393640991;6391.07329437229;6401.9039714404325;6407.519436042222;6413.3266007069515;5764.337101233328;5818.0981947798155;6052.848678782978;6177.135201071854;6183.165272688219;6994.755183568551;7068;7084.36240738336;7098.36114749675;7101.325374089453;6926.06498122747;6927.930720692729;6939.167299924042;6950.860982780215;6976.4767642426;6672.399484046459;6920.30796920304;7007.334303241184;7023.05188799526;7051.717931282897;5714.79998200061;6392.840178995562;6723.865522689616;6786.083878676315;6849.452043836581;6872.072270243453;6929.7228110875;6932.168139823253;6937;6971.98906158594;6375.585586936834;6419.614823110628;6659.833504162241;6714.294999025032;6723.361280678328;6612.272650008399;6677.6661166940485;6680.3987641112535;6708.966455167693;6735.595864248161;6463.741450341934;6557.770478033437;6567.179102612174;6590.373914478117;6591.736330546916;6341.175647165839;6400.295967443671;6678.83302917431;6683.565568238049;6711.765088221889;6423.261324947691;6460.353964603502;6498.155239818793;6510.641914694548;6526.5105117117355 03:51:46 INFO - PID 1653 | |1;ext.html;777.9961100194498;810.4893916832394;836.560805577072;1075.7364445710803;1236.2211806561866;37911;39231.60768392323;39311.606883931185;39344.409833852515;39424.21151576967;39569.01077473065;40007.199856002866;40162.19675606486;40180.59819401805;40239;7819.178986206452;7983.161768014362;8174.26431621154;8374.330053595728;8410.873836892451;7261.782146535613;8314.792130196733;8382.916170838305;8407.621657025418;8408.033076196254;8314.251717345438;8344.3741719371;8393.53835539045;8394.24451799338;8408;8337.291330236938;8346.540940248284;8378.874316885222;8397.118302578263;8402.915970840275;8205.58972051395;8251.257386835214;8251.876221856677;8254.876176857351;8308.54303013334;37323.44014839779;37499;37560.43659345112;37610.8119459401;37659.811700941595;3634.1459756957356;3659.4876717259604;3672.338978983782;3674.375356189454;3676.7242456815848;30353.089407317817;30479.085627431214;30493.69506304931;30551.694483055107;30684.2328941776;3077.092257783959;3090.922726931822;3100.51941948996;3132.561441398206;3133.9216519587194;241338.58661413335;243518;269353.6532317345;299013;299448;48980;49065;49081.75459122753;49120;49357;50115;50577.49422505728;50776.492235077916;50778.49221507811;50962.23556646653;14645.974781765388;14671.55985320442;14771.187584682946;14798.186099764616;14819.77770333446;11689.298642081332;11692.824607630891;11718.23831450953;11755.118366122575;11811.586594469152;12763.361831908367;13418.597442076752;14293.928530357281;14297.999140060096;14338.283085845665;4910.582600478928;5700.6888415664625;5784.21913041734;5953.880922381528;6311.526635502356;980.7548112971757;1002.8144793213279;1004.8342023566175;1051.7064011266032;1054.651964851597;23179.420514486934;23181.02213023389;23228.419289517897;24013.75986240115;24278.75721242765;1000.3047881722279;1086.2884810449168;1094.578587243901;1131.688785583971;1140.680609429361;11741.530338786524;11895.286282823059;12030.4586293617;12449.532630990649;12470.376481176087;294339;297024;297078;297351;297655;42683;54415.72792136093;54757.178642320396;55046.72476637591;55060.72469637706;1501.5795577238173;1503.5263891874242;1540.8998415102983;1540.9691806163814;1555.3078879898562;22873.771262287828;22876.85615719174;22938.42653933665;24020.34160413132;24089.397765056015;38136.61863381442;38179.80910095432;38433.807830960664;38447.03882402962;38672;254050;254433;254908;255872;255998;36288.63711362958;36421.635783641825;36431;36487.27025459529;38025.049373765876;43102.92242693958;44826.55173448224;45512.772436138934;45617.85955351143;45659;29333.973310934867;29863.55204671975;30061.248468788453;30214.395712086076;31497.897573584814;21361.67957480608;30273.904855154073;30912;31423.84288078545;31569.36861262716;35903.46144807882;37155.44266835946;37690.811545943194;37808.62191378051;37891.81054094822;30260;31035;31226.063218103493;31777.682223178395;31876.840615796773;22708.432289192904;24409.877950610135;24583.631245530974;24591.38521536976;24604.876975615007 03:51:46 INFO - PID 1653 | |2;jquery.html;489.62299029747095;502.6707506583189;503.1220520192265;519.8466452396542;527.1565495207666;21391.893040534775;21467;22206.00072996715;22272.88863555682;24376.878115609397;24797.752022479814;25499;25509;25541.74458255417;25566.360840978985;6987.161540615133;7161;7164.570125792443;7177.2463891291445;7179.533330333537;6790.762323318685;6841.760538381158;6922.376986071253;7022.894656580155;7068.151821781367;6103.45068943795;6904.620245886475;6910.689018994132;6914.446844252448;6931.064306318633;6891.414229790474;6900.5859648421;6917.446604271646;6924.653767311639;6930.896036559456;6780.389764921131;6785.830354241183;6786.694598743068;6793.524453288272;6795.3204679531655;30374.87375883689;31081.22296942572;31155.376892462024;31170.220744481343;31502.212444689067;2309.307207837659;2350.3654013416453;2352.1532248390567;2352.176738141654;2360.091364824538;18267.81732182688;18437.985910774903;18741.531461713428;18806.059697015233;18822.81177188238;2085.728855248823;2106.3996760923214;2161.5352699169753;2174.6846707227523;2222.355516900097;198344;202434.9878250614;214077;214584.92707536518;215824;13254.138480998898;14088.506902258372;14584.90613204014;14802.925985370219;15008.874334424961;20553.794462055488;20577.588448230952;20580;20584.79415205859;20662.690059649118;8623.698170564001;9035.683751068682;9732.659356922473;9791.657291994747;10202.642907498202;9265.305102117369;9270.35107542479;9280;9309.581068851783;9340.53297335144;7601.961990190013;9175.495347755805;9232.723018309462;9292.72121836346;9295.535223238947;2235.966460503094;2476.3561474016888;2655.4556315955183;2677.3039009857575;2804.845733484637;929.3958926697701;948.3645957208687;969.5443141723379;978.4618459847198;986.1371300112402;22146.335609931728;22180.223692170697;22221.444463888532;22301.888490557445;22487.55024899493;1005.266145925104;1033.5504055735778;1046.340805292676;1047.4710271312938;1052.9838705649086;10486.846446890835;11149.944250278697;11195.832062519068;11519.942400287944;11658.06735461178;527164;531125;534548.6545134499;537827;541670;153657;159683;161028;161692;161836.19081904515;1205.0841360566033;1207.3661327802974;1256.1772039314264;1261.426051146726;1263.9873601264053;22158.556828863653;22175.55648886981;23094.960726767;23414.882925585945;23778.048878045378;66996;71692.64153679198;72904.63547682228;73052;73860;96114;97088;97167.57081073029;97443.53834692344;97502;9703.466309353053;11732.41337933117;11790.41047947582;11809.940950295537;11840;17495.912520437316;18699.906500467412;18920.905395472935;18954.43136705901;18989.905050475212;15254.923725381303;15263.542093737206;15414.075155490706;15446.768298475756;15455.227238637799;13633.318334083455;13651.863481365059;13839.930800345934;13905.930470347583;13933.58199254024;15507.612309692347;15516.22418879074;15735.213239338216;15821.841781582038;15888.443904463289;11403.942980285046;11565.248258863146;11676.241044332091;11728.296302221894;11742.530298787953;14783.926080369529;14836.035657682216;15018.474353397578;15038.248087595795;15042.924785376441 03:51:46 INFO - PID 1653 | |3;mootools.html;1228.195531926588;1443.6246575890266;1444.0397135904618;1446.2913172545457;1460.9196494192815;12896.161749486277;12995;13546.39041243144;13560.932195339023;13818.101823381474;13517.188968661887;13688.931555342235;13752.037357384987;13770.931145344259;13784.17294962303;2577.0464927976627;2615.411532405203;2624.278323461054;2626.120249716347;2628.2509484796838;2662.454196889633;2712.4846279206986;2715.592661100841;2716.0629582793904;2723.4553089382102;2663.853488058156;2686.3418462476625;2687.341601307682;2690.4081102157493;2693.2593536777445;2641.9471610567775;2661.5741481362984;2665.640138581286;2668.239551727768;2672.465506898628;2614.5293847107705;2632.3945492536823;2635.3806855388966;2721.8911243550233;2725.11433784021;13157.342132893413;13211.075224734272;13211.339433028406;13218.60344189676;13233.801492977516;7235.927640723578;7544.396448284124;7546.169921308651;7563.130240022413;7584.976028236173;4319.654427645813;4477.126960242754;4518.344839998182;4544;4562.133194693031;1365.4128724648397;1388.9166650001032;1407.0924253856308;1412.1103704666098;1416.05832121639;18473.445796625987;19132.90433547837;19909.900450497655;20114.39656810298;20115.698264526043;6431.678416079178;6479.481641468766;6723.092382528296;6729.25978142404;6792.45660347171;14106.788398174036;14127.929360353339;14139.64650883736;14146.36341364641;14158;7314.817129571697;7424.294692004251;7429.888551671729;7441.181470038291;7446.73936412223;5745.368009518949;6678.465722742169;6769.593824370455;6813.659317034128;6837.111175547247;4538.750368729686;4973.254011898244;5069.44236134025;5122.615803814729;5155.53600175986;1450.3610919660716;1477.958039582092;1479.4747864507945;1499.5051632961315;1500.0399744163744;826.6362800367939;836.7908022994252;855.4140413816556;861.1990848510945;892.6920212526668;6758.695858686371;6761.4590832733265;7202.49582529218;8006;8262.628181731836;603.1856993059334;647.2491909385091;698.4796326736614;703.5637904499141;706.7455715942359;4337.132573485315;4797.520247975174;4813.711177329302;5024.547790698782;5203.297554830049;17862.642747145244;18460.538486537946;18531.35140270111;18579;18607;18043.18805653749;18472.722909156375;18484.630307394047;18489;18518.16668249932;8168.632411541495;8261.504309741435;9046.321525885585;9131.936288351753;9509.66716164931;7628.656710448154;8103.756887293391;8169.5506747129475;8214.096449390443;8515;14357.784633230718;14577.85422145765;14579.979401441797;14626.634334141307;14869.628259293604;13641.56763539814;13983.370748316147;14846.480373186887;14972.251387430368;15159.166245856584;8959.596818143069;9049.411788233743;9654.565544550653;9694.127528522467;9717.27120465982;12309;12371.319577422968;12415.503379864704;12429.875701242872;12452.31512266834;9830.55762490704;9923.354981926152;10215.489225538842;10273.537690804082;10339.534720937425;8137.267645911897;9577;9904.158146557624;9927.305088643725;9938.552765125442;8886.511241881759;9065;9065.546722663972;9074.31942604294;9103.953045399863;6335.683215839282;6470.158879345655;6679.866402672016;6894.253628161785;6905.309469053255;5388.838334849961;6811.773880701521;6905.37851593359;7155.713771449084;7237.059182306267 03:51:46 INFO - PID 1653 | |4;prototype.html;380.34580521503005;381.27938196808054;395.20958083832335;454.22781271837874;455.8791920141163;6055.697215139247;6609.636469994149;6627.9337206627915;6673.6329501877435;6713.7985860424205;5062.696238225709;6124.387561243873;6235.968820155893;6483.1571895653615;6526.869462610745;4146.771927543984;4791.952080479195;4815.614750819944;4816.736134017024;4867.561919427251;4158;4654.952635656967;4732.911430371017;4826.203676393391;4846.709197448146;4781.37842080529;4796.856094317176;4800.807967681289;4802.255650374198;4810.542998415145;4798.352222449995;4813.157697402976;4817.735024573647;4834.06024090367;4844.15227335215;4236.279832428495;4242.215190189825;4249.596288352602;4250.277452833026;4253.489581250239;6401.039844023388;6406.4234218920055;6438.967805160944;6460.93539064608;6476.215227452194;4865.635077369211;5528.834134975958;5750.166225897264;5761.279840019997;5814.389489103625;6499.642519661416;6904.551204171763;6975.8604827903155;6993.860122797516;7001.264867188923;4476.194285028693;4486.214912390351;4805.783739731721;4846.418429788448;4854;6547.934520654732;7038.824029399255;7244.492885498017;7314.975903373532;7423.851522969564;4683.765811709401;4841.782119804618;4877.878053048702;4900.313956046157;4950.737561921759;4178.791060446965;4493.415855938772;5012.298278241049;5014.172661510883;5023.296738456546;5441.428649991773;5609.747561359749;5618.157276408489;5695.743691533891;5709.68596727184;5288.497592728761;6221.889055472264;6929.480288978246;6983.580985140908;7015.263397343308;1457.7959085728007;1682.973386234396;1702.8722845786615;1730.4981555348809;1732.3936622182387;951.5052172870069;1035.254616675992;1162.0761494611804;1162.151629310608;1163.040491594438;6500.512461565401;6511.804645860537;6525.869482610321;6551.934480655228;6581.40767330933;7231.204567497571;7250.1299844018;7255.637218139072;7262.600556969312;7264.963675181696;5477.890442191134;6513.413792758674;6516.706748196342;6525.934740652628;6540.9345906541275;3384.61076976146;3733.738638295293;3777.3002149033045;3807.466954626299;3822.5604055533413;6646.6676666166495;6937.028815965768;7407.333339999426;7411.481196316314;7464.365528930101;6810.8297292567095;7388.931099292099;7486.588237646982;7492.7636939904305;7511.549307041595;6405.743770249231;6624.701888415033;7361.889571656537;7394.667239974214;7438.776836694799;7175.46184036189;7187.6765545551625;7211.42308615299;7245.311695388929;7247.2390399007345;6625.900611490928;7077.442962548231;7099.183593886769;7138.286171382821;7198.5680859148615;6762.357576030269;7392.297731715478;7426.220246874216;7473.028506294147;7562.621868906643;4678.157931572349;4871.610271178368;4904.558589726942;4930.654854160174;4931.052234367477;6074.544409169242;6651.334866513504;6942.722291108299;7273.890252007599;7311.3785328247695;6926.757563485254;6957.304269573002;6967.247937064933;6967.512274140761;6969.337912898267;6956;6981.650917454209;7002.984567237824;7019.19279282889;7033.964830175816;6485.708143133476;6504.772332968323;6534.281229064894;6779.93220067793;6884.827879303058;6513.567015256636;6526.510511711545;6931.09895713554;6949;6952.721891124298;6750.05499230118;6767.492438067263;6774.220964589135;6777.796666100025;6781.864362712817 03:51:46 INFO - PID 1653 | |5;yui.html;749.1571981520789;810.9351251899849;823.0945959444612;843.586642545153;851.0340763233728;14843.851561484382;15261.160636165007;16845.49463516095;17401.868878522888;17442.91278543607;16282.348706051744;17838.286468541242;18142.002189879553;18159.54601134972;18217.453476395694;978.8238117138912;996.3822430093317;1012.9392236465799;1017.460745804721;1017.6031347774336;998.7153661206557;1013.761765984993;1014.4978235773266;1015.9238057145707;1026.1841835740604;943.702733638905;1043.5825669732092;1046.4192373232852;1052.1319911073394;1078.4553800330823;1020.6274709730932;1030.6959446963165;1038.7091614347958;1042.6715584590906;1049.9002594753485;761.756238003839;770.6647608290411;774.7094839435211;775.8176828445311;782.9138794732575;16842.578935526588;16987.91506042474;17155.228014739365;17177.96932184073;17407.47777566675;16380.918095409566;16445.506634800975;16804.41184558547;16876.746848797276;16887;14135.505257316048;14472.855271447255;14507.782383264259;14616.70766584673;14627.414903403851;3589.0668426209113;3609.097725568608;3609.151849315409;3614.331348700499;3615.2407994321225;145605.2719736405;147155.52844471525;147974.26012869974;149227;149849;12592.496300147892;12745.04412169091;13564.457421703219;13573.253471059186;13792.517261895786;22666.773332266464;26119.216423507325;26685.733142668323;26829.597556036675;26859.865700671373;6697.832554186087;7536.585487798224;7658.65536050879;7798.064232292161;7824.061112666516;3831.5593706724085;3913.0608653922855;3923.43110249018;3923.6664883484655;3929.8821035368414;4460.50934397216;4510.9173798288175;4535.705179163344;4555.521670224645;4571.177188106173;900.7704483380139;905.3798148268457;921.1157289002632;921.4517362169632;923.3905622289257;627.3036929008797;645.8772833161778;647.685872351909;663.93692599203;675.1425689374565;11225.101991840633;11275.84861490444;11735.88264117365;11934.28394296345;12258.44836982338;651.17951381259;659.1200747002805;667.1360588038502;678.657278074579;692.9688164032631;5602.943970560243;5642.548596112301;5738.110592858114;5778.728679690461;5967.970160149258;113523.43238283756;114995.42502287435;116959.41520292344;118081.40959295149;124883.37558312331;96879.51560242295;97823;104100.47949760202;104353.26057066934;105467.41798873023;88907.66638500428;90267.64598531027;93331;96886.51556742312;98716;10784.352938823695;10948.069414099891;11024.456576079196;11542.826857596974;12026.518939242333;41056.58943410647;44141;44192.55807441884;44223.33664995092;44740;65545.34454655393;66943;71775.92336115066;72670.27329726635;77095;16312.347506099622;16859.072750998814;16904.830951690325;16952.491425257263;17057.317707292066;19088.713669295248;19282;19312;19348;19424.125914333606;16504.25730842139;17554.824451755318;17998.280068797103;18223.088845557933;18224;15251.237438128272;17752.822471775635;17850.732239016164;18162.182701778715;18339;17727.379541715978;17795.46613601594;17805.910970445064;17911.820881791016;18034.909825450788;11250.324980501196;11722.58970936013;11874.109441792072;12222.816657750318;12402.689932751753;12316.815247771112;12499.375031248583;13170.736585268432;13231.3384330785;13339.866601333862 03:51:46 INFO - PID 1653 | __end_tp_report 03:51:46 INFO - PID 1653 | __start_cc_report 03:51:46 INFO - PID 1653 | _x_x_mozilla_cycle_collect,450 03:51:46 INFO - PID 1653 | __end_cc_report 03:51:46 INFO - PID 1653 | __startTimestamp1497091906058__endTimestamp 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | ------- Summary: start ------- 03:51:46 INFO - PID 1653 | Number of tests: 6 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | [#0] dojo.html Cycles:165 Average:6226.34 Median:6413.74 stddev:767.27 (12.0%) stddev-sans-first:739.70 03:51:46 INFO - PID 1653 | Values: 3521.3 3595.4 3598.6 3614.1 3616.9 4868.4 6131.7 6297.7 6307.7 6362.3 5415.4 6136.0 6196.9 6294.9 6296.2 5442.6 5464.0 5480.5 5500.3 5574.5 5360.3 5403.3 5576.2 5648.9 5667.8 5529.9 5552.5 5558.6 5597.1 5630.3 5288.7 5364.8 5507.0 5519.8 5537.3 4597.9 5494.6 5508.7 5527.6 5528.9 5254.6 6191.5 6292.8 6305.6 6320.9 5696.8 6436.8 6510.8 6685.3 6795.4 6965.6 6969.1 6978.0 6982.8 6994.4 6403.5 6411.6 6413.7 6414.3 6432.8 6602.3 6714.0 6967.7 7068.6 7083.3 5608.3 6355.3 6696.5 6707.5 6721.1 6430.0 6814.2 6902.2 6924.2 6927.4 6311.2 6347.9 6771.0 6794.6 6807.4 6445.9 6480.6 6721.5 6834.4 6849.2 5361.9 5414.9 5417.5 5429.9 5456.5 4836.0 4849.9 4864.8 4868.3 4872.7 6231.7 6260.6 6315.6 6335.6 6404.6 6914.9 6938.8 6981.1 6985.7 6988.9 6384.0 6391.1 6401.9 6407.5 6413.3 5764.3 5818.1 6052.8 6177.1 6183.2 6994.8 7068.0 7084.4 7098.4 7101.3 6926.1 6927.9 6939.2 6950.9 6976.5 6672.4 6920.3 7007.3 7023.1 7051.7 5714.8 6392.8 6723.9 6786.1 6849.5 6872.1 6929.7 6932.2 6937.0 6972.0 6375.6 6419.6 6659.8 6714.3 6723.4 6612.3 6677.7 6680.4 6709.0 6735.6 6463.7 6557.8 6567.2 6590.4 6591.7 6341.2 6400.3 6678.8 6683.6 6711.8 6423.3 6460.4 6498.2 6510.6 6526.5 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | [#1] ext.html Cycles:180 Average:43093.06 Median:33890.15 stddev:71894.26 (212.1%) stddev-sans-first:72025.74 03:51:46 INFO - PID 1653 | Values: 778.0 810.5 836.6 1075.7 1236.2 37911.0 39231.6 39311.6 39344.4 39424.2 39569.0 40007.2 40162.2 40180.6 40239.0 7819.2 7983.2 8174.3 8374.3 8410.9 7261.8 8314.8 8382.9 8407.6 8408.0 8314.3 8344.4 8393.5 8394.2 8408.0 8337.3 8346.5 8378.9 8397.1 8402.9 8205.6 8251.3 8251.9 8254.9 8308.5 37323.4 37499.0 37560.4 37610.8 37659.8 3634.1 3659.5 3672.3 3674.4 3676.7 30353.1 30479.1 30493.7 30551.7 30684.2 3077.1 3090.9 3100.5 3132.6 3133.9 241338.6 243518.0 269353.7 299013.0 299448.0 48980.0 49065.0 49081.8 49120.0 49357.0 50115.0 50577.5 50776.5 50778.5 50962.2 14646.0 14671.6 14771.2 14798.2 14819.8 11689.3 11692.8 11718.2 11755.1 11811.6 12763.4 13418.6 14293.9 14298.0 14338.3 4910.6 5700.7 5784.2 5953.9 6311.5 980.8 1002.8 1004.8 1051.7 1054.7 23179.4 23181.0 23228.4 24013.8 24278.8 1000.3 1086.3 1094.6 1131.7 1140.7 11741.5 11895.3 12030.5 12449.5 12470.4 294339.0 297024.0 297078.0 297351.0 297655.0 42683.0 54415.7 54757.2 55046.7 55060.7 1501.6 1503.5 1540.9 1541.0 1555.3 22873.8 22876.9 22938.4 24020.3 24089.4 38136.6 38179.8 38433.8 38447.0 38672.0 254050.0 254433.0 254908.0 255872.0 255998.0 36288.6 36421.6 36431.0 36487.3 38025.0 43102.9 44826.6 45512.8 45617.9 45659.0 29334.0 29863.6 30061.2 30214.4 31497.9 21361.7 30273.9 30912.0 31423.8 31569.4 35903.5 37155.4 37690.8 37808.6 37891.8 30260.0 31035.0 31226.1 31777.7 31876.8 22708.4 24409.9 24583.6 24591.4 24604.9 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | [#2] jquery.html Cycles:180 Average:39861.72 Median:12361.76 stddev:94327.06 (763.1%) stddev-sans-first:94545.35 03:51:46 INFO - PID 1653 | Values: 489.6 502.7 503.1 519.8 527.2 21391.9 21467.0 22206.0 22272.9 24376.9 24797.8 25499.0 25509.0 25541.7 25566.4 6987.2 7161.0 7164.6 7177.2 7179.5 6790.8 6841.8 6922.4 7022.9 7068.2 6103.5 6904.6 6910.7 6914.4 6931.1 6891.4 6900.6 6917.4 6924.7 6930.9 6780.4 6785.8 6786.7 6793.5 6795.3 30374.9 31081.2 31155.4 31170.2 31502.2 2309.3 2350.4 2352.2 2352.2 2360.1 18267.8 18438.0 18741.5 18806.1 18822.8 2085.7 2106.4 2161.5 2174.7 2222.4 198344.0 202435.0 214077.0 214584.9 215824.0 13254.1 14088.5 14584.9 14802.9 15008.9 20553.8 20577.6 20580.0 20584.8 20662.7 8623.7 9035.7 9732.7 9791.7 10202.6 9265.3 9270.4 9280.0 9309.6 9340.5 7602.0 9175.5 9232.7 9292.7 9295.5 2236.0 2476.4 2655.5 2677.3 2804.8 929.4 948.4 969.5 978.5 986.1 22146.3 22180.2 22221.4 22301.9 22487.6 1005.3 1033.6 1046.3 1047.5 1053.0 10486.8 11149.9 11195.8 11519.9 11658.1 527164.0 531125.0 534548.7 537827.0 541670.0 153657.0 159683.0 161028.0 161692.0 161836.2 1205.1 1207.4 1256.2 1261.4 1264.0 22158.6 22175.6 23095.0 23414.9 23778.0 66996.0 71692.6 72904.6 73052.0 73860.0 96114.0 97088.0 97167.6 97443.5 97502.0 9703.5 11732.4 11790.4 11809.9 11840.0 17495.9 18699.9 18920.9 18954.4 18989.9 15254.9 15263.5 15414.1 15446.8 15455.2 13633.3 13651.9 13839.9 13905.9 13933.6 15507.6 15516.2 15735.2 15821.8 15888.4 11403.9 11565.2 11676.2 11728.3 11742.5 14783.9 14836.0 15018.5 15038.2 15042.9 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | [#3] mootools.html Cycles:180 Average:8044.40 Median:2722.67 stddev:5315.77 (195.2%) stddev-sans-first:5306.01 03:51:46 INFO - PID 1653 | Values: 1228.2 1443.6 1444.0 1446.3 1460.9 12896.2 12995.0 13546.4 13560.9 13818.1 13517.2 13688.9 13752.0 13770.9 13784.2 2577.0 2615.4 2624.3 2626.1 2628.3 2662.5 2712.5 2715.6 2716.1 2723.5 2663.9 2686.3 2687.3 2690.4 2693.3 2641.9 2661.6 2665.6 2668.2 2672.5 2614.5 2632.4 2635.4 2721.9 2725.1 13157.3 13211.1 13211.3 13218.6 13233.8 7235.9 7544.4 7546.2 7563.1 7585.0 4319.7 4477.1 4518.3 4544.0 4562.1 1365.4 1388.9 1407.1 1412.1 1416.1 18473.4 19132.9 19909.9 20114.4 20115.7 6431.7 6479.5 6723.1 6729.3 6792.5 14106.8 14127.9 14139.6 14146.4 14158.0 7314.8 7424.3 7429.9 7441.2 7446.7 5745.4 6678.5 6769.6 6813.7 6837.1 4538.8 4973.3 5069.4 5122.6 5155.5 1450.4 1478.0 1479.5 1499.5 1500.0 826.6 836.8 855.4 861.2 892.7 6758.7 6761.5 7202.5 8006.0 8262.6 603.2 647.2 698.5 703.6 706.7 4337.1 4797.5 4813.7 5024.5 5203.3 17862.6 18460.5 18531.4 18579.0 18607.0 18043.2 18472.7 18484.6 18489.0 18518.2 8168.6 8261.5 9046.3 9131.9 9509.7 7628.7 8103.8 8169.6 8214.1 8515.0 14357.8 14577.9 14580.0 14626.6 14869.6 13641.6 13983.4 14846.5 14972.3 15159.2 8959.6 9049.4 9654.6 9694.1 9717.3 12309.0 12371.3 12415.5 12429.9 12452.3 9830.6 9923.4 10215.5 10273.5 10339.5 8137.3 9577.0 9904.2 9927.3 9938.6 8886.5 9065.0 9065.5 9074.3 9104.0 6335.7 6470.2 6679.9 6894.3 6905.3 5388.8 6811.8 6905.4 7155.7 7237.1 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | [#4] prototype.html Cycles:180 Average:5670.37 Median:6500.08 stddev:1760.79 (27.1%) stddev-sans-first:1720.38 03:51:46 INFO - PID 1653 | Values: 380.3 381.3 395.2 454.2 455.9 6055.7 6609.6 6627.9 6673.6 6713.8 5062.7 6124.4 6236.0 6483.2 6526.9 4146.8 4792.0 4815.6 4816.7 4867.6 4158.0 4655.0 4732.9 4826.2 4846.7 4781.4 4796.9 4800.8 4802.3 4810.5 4798.4 4813.2 4817.7 4834.1 4844.2 4236.3 4242.2 4249.6 4250.3 4253.5 6401.0 6406.4 6439.0 6460.9 6476.2 4865.6 5528.8 5750.2 5761.3 5814.4 6499.6 6904.6 6975.9 6993.9 7001.3 4476.2 4486.2 4805.8 4846.4 4854.0 6547.9 7038.8 7244.5 7315.0 7423.9 4683.8 4841.8 4877.9 4900.3 4950.7 4178.8 4493.4 5012.3 5014.2 5023.3 5441.4 5609.7 5618.2 5695.7 5709.7 5288.5 6221.9 6929.5 6983.6 7015.3 1457.8 1683.0 1702.9 1730.5 1732.4 951.5 1035.3 1162.1 1162.2 1163.0 6500.5 6511.8 6525.9 6551.9 6581.4 7231.2 7250.1 7255.6 7262.6 7265.0 5477.9 6513.4 6516.7 6525.9 6540.9 3384.6 3733.7 3777.3 3807.5 3822.6 6646.7 6937.0 7407.3 7411.5 7464.4 6810.8 7388.9 7486.6 7492.8 7511.5 6405.7 6624.7 7361.9 7394.7 7438.8 7175.5 7187.7 7211.4 7245.3 7247.2 6625.9 7077.4 7099.2 7138.3 7198.6 6762.4 7392.3 7426.2 7473.0 7562.6 4678.2 4871.6 4904.6 4930.7 4931.1 6074.5 6651.3 6942.7 7273.9 7311.4 6926.8 6957.3 6967.2 6967.5 6969.3 6956.0 6981.7 7003.0 7019.2 7034.0 6485.7 6504.8 6534.3 6779.9 6884.8 6513.6 6526.5 6931.1 6949.0 6952.7 6750.1 6767.5 6774.2 6777.8 6781.9 03:51:46 INFO - PID 1653 | 03:51:46 INFO - PID 1653 | [#5] yui.html Cycles:180 Average:24083.47 Median:18016.59 stddev:35643.44 (197.8%) stddev-sans-first:35700.36 03:51:46 INFO - PID 1653 | Values: 749.2 810.9 823.1 843.6 851.0 14843.9 15261.2 16845.5 17401.9 17442.9 16282.3 17838.3 18142.0 18159.5 18217.5 978.8 996.4 1012.9 1017.5 1017.6 998.7 1013.8 1014.5 1015.9 1026.2 943.7 1043.6 1046.4 1052.1 1078.5 1020.6 1030.7 1038.7 1042.7 1049.9 761.8 770.7 774.7 775.8 782.9 16842.6 16987.9 17155.2 17178.0 17407.5 16380.9 16445.5 16804.4 16876.7 16887.0 14135.5 14472.9 14507.8 14616.7 14627.4 3589.1 3609.1 3609.2 3614.3 3615.2 145605.3 147155.5 147974.3 149227.0 149849.0 12592.5 12745.0 13564.5 13573.3 13792.5 22666.8 26119.2 26685.7 26829.6 26859.9 6697.8 7536.6 7658.7 7798.1 7824.1 3831.6 3913.1 3923.4 3923.7 3929.9 4460.5 4510.9 4535.7 4555.5 4571.2 900.8 905.4 921.1 921.5 923.4 627.3 645.9 647.7 663.9 675.1 11225.1 11275.8 11735.9 11934.3 12258.4 651.2 659.1 667.1 678.7 693.0 5602.9 5642.5 5738.1 5778.7 5968.0 113523.4 114995.4 116959.4 118081.4 124883.4 96879.5 97823.0 104100.5 104353.3 105467.4 88907.7 90267.6 93331.0 96886.5 98716.0 10784.4 10948.1 11024.5 11542.8 12026.5 41056.6 44141.0 44192.6 44223.3 44740.0 65545.3 66943.0 71775.9 72670.3 77095.0 16312.3 16859.1 16904.8 16952.5 17057.3 19088.7 19282.0 19312.0 19348.0 19424.1 16504.3 17554.8 17998.3 18223.1 18224.0 15251.2 17752.8 17850.7 18162.2 18339.0 17727.4 17795.5 17805.9 17911.8 18034.9 11250.3 11722.6 11874.1 12222.8 12402.7 12316.8 12499.4 13170.7 13231.3 13339.9 03:51:46 INFO - PID 1653 | -------- Summary: end -------- 03:51:46 INFO - PID 1653 | 03:51:46 INFO - TEST-INFO | 1653: exit 0 03:51:46 INFO - TEST-OK | dromaeo_css | took 1143263ms 03:51:46 INFO - TEST-START | kraken 03:51:46 INFO - operating with platform_type : mac_ 03:51:46 INFO - Initialising browser for kraken test... 03:51:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmp5lJYEZ/profile 03:51:46 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 03:52:20 INFO - TEST-INFO | 1684: killed by SIGTERM 03:52:20 INFO - Browser initialized. 03:52:20 INFO - Running cycle 1/1 for kraken test... 03:52:20 INFO - TEST-INFO | started process 1692 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmp5lJYEZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:52:22 INFO - PID 1692 | 2017-06-10 03:52:22.264 plugin-container[1693:11285] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 03:52:22 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:52:22 INFO - PID 1692 | 2017-06-10 03:52:22.265 plugin-container[1693:11285] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 03:52:22 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:52:22 INFO - PID 1692 | 2017-06-10 03:52:22.265 plugin-container[1693:11285] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:52:23 INFO - PID 1692 | 2017-06-10 03:52:23.080 plugin-container[1694:11333] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 03:52:23 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:52:23 INFO - PID 1692 | 2017-06-10 03:52:23.080 plugin-container[1694:11333] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 03:52:23 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:52:23 INFO - PID 1692 | 2017-06-10 03:52:23.080 plugin-container[1694:11333] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:52:32 INFO - PID 1692 | RSS: Main: 253534208 03:52:32 INFO - PID 1692 | 03:52:35 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 03:52:36 INFO - PID 1692 | RSS: Main: 266256384 03:52:36 INFO - PID 1692 | 03:52:39 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 03:52:39 INFO - PID 1692 | RSS: Main: 276066304 03:52:39 INFO - PID 1692 | 03:52:43 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 03:52:43 INFO - PID 1692 | RSS: Main: 278585344 03:52:43 INFO - PID 1692 | 03:52:46 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 03:52:46 INFO - PID 1692 | RSS: Main: 280096768 03:52:46 INFO - PID 1692 | 03:52:48 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:52:49 INFO - PID 1692 | RSS: Main: 280477696 03:52:49 INFO - PID 1692 | 03:52:52 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 03:52:52 INFO - PID 1692 | RSS: Main: 289189888 03:52:52 INFO - PID 1692 | 03:52:55 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 03:52:56 INFO - PID 1692 | RSS: Main: 287854592 03:52:56 INFO - PID 1692 | 03:52:59 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 03:52:59 INFO - PID 1692 | RSS: Main: 286806016 03:52:59 INFO - PID 1692 | 03:53:01 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:53:02 INFO - PID 1692 | RSS: Main: 286691328 03:53:02 INFO - PID 1692 | 03:53:03 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:53:04 INFO - PID 1692 | RSS: Main: 286482432 03:53:04 INFO - PID 1692 | 03:53:06 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:53:07 INFO - PID 1692 | RSS: Main: 286547968 03:53:07 INFO - PID 1692 | 03:53:10 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:53:10 INFO - PID 1692 | RSS: Main: 286203904 03:53:10 INFO - PID 1692 | 03:53:13 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:53:14 INFO - PID 1692 | RSS: Main: 286195712 03:53:14 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 03:53:16 INFO - PID 1692 | RSS: Main: 288129024 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | __start_tp_report 03:53:16 INFO - PID 1692 | _x_x_mozilla_page_load 03:53:16 INFO - PID 1692 | _x_x_mozilla_page_load_details 03:53:16 INFO - PID 1692 | |i|pagename|runs| 03:53:16 INFO - PID 1692 | |0;ai-astar;116;151;103;163;172;101;161;167;158;165 03:53:16 INFO - PID 1692 | |1;audio-beat-detection;163;106;121;186;144;140;129;111;169;102 03:53:16 INFO - PID 1692 | |2;audio-dft;197;208;206;228;216;268;271;197;199;197 03:53:16 INFO - PID 1692 | |3;audio-fft;71;96;110;187;49;78;85;87;168;60 03:53:16 INFO - PID 1692 | |4;audio-oscillator;70;70;115;73;71;83;107;70;69;107 03:53:16 INFO - PID 1692 | |5;imaging-gaussian-blur;92;89;93;88;90;89;89;90;89;88 03:53:16 INFO - PID 1692 | |6;imaging-darkroom;113;154;96;95;95;96;96;96;96;97 03:53:16 INFO - PID 1692 | |7;imaging-desaturate;90;89;89;90;96;90;90;94;93;89 03:53:16 INFO - PID 1692 | |8;json-parse-financial;50;49;53;81;77;79;80;66;53;52 03:53:16 INFO - PID 1692 | |9;json-stringify-tinderbox;56;55;59;64;60;64;56;64;83;56 03:53:16 INFO - PID 1692 | |10;stanford-crypto-aes;84;83;86;80;107;84;89;88;111;88 03:53:16 INFO - PID 1692 | |11;stanford-crypto-ccm;134;137;134;141;127;145;146;152;132;151 03:53:16 INFO - PID 1692 | |12;stanford-crypto-pbkdf2;180;183;176;175;184;176;177;181;181;179 03:53:16 INFO - PID 1692 | |13;stanford-crypto-sha256-iterative;66;67;64;64;67;69;66;92;81;68 03:53:16 INFO - PID 1692 | __end_tp_report 03:53:16 INFO - PID 1692 | __start_cc_report 03:53:16 INFO - PID 1692 | _x_x_mozilla_cycle_collect,1172 03:53:16 INFO - PID 1692 | __end_cc_report 03:53:16 INFO - PID 1692 | __startTimestamp1497091996396__endTimestamp 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | ------- Summary: start ------- 03:53:16 INFO - PID 1692 | Number of tests: 14 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#0] ai-astar Cycles:10 Average:145.70 Median:162.00 stddev:27.76 (17.1%) stddev-sans-first:27.28 03:53:16 INFO - PID 1692 | Values: 116.0 151.0 103.0 163.0 172.0 101.0 161.0 167.0 158.0 165.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:142.00 stddev:28.56 (20.1%) stddev-sans-first:28.71 03:53:16 INFO - PID 1692 | Values: 163.0 106.0 121.0 186.0 144.0 140.0 129.0 111.0 169.0 102.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#2] audio-dft Cycles:10 Average:218.70 Median:212.00 stddev:28.55 (13.5%) stddev-sans-first:29.18 03:53:16 INFO - PID 1692 | Values: 197.0 208.0 206.0 228.0 216.0 268.0 271.0 197.0 199.0 197.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#3] audio-fft Cycles:10 Average:99.10 Median:74.50 stddev:44.99 (60.4%) stddev-sans-first:46.56 03:53:16 INFO - PID 1692 | Values: 71.0 96.0 110.0 187.0 49.0 78.0 85.0 87.0 168.0 60.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:70.00 stddev:18.62 (26.6%) stddev-sans-first:19.10 03:53:16 INFO - PID 1692 | Values: 70.0 70.0 115.0 73.0 71.0 83.0 107.0 70.0 69.0 107.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.50 stddev:1.64 (1.8%) stddev-sans-first:1.51 03:53:16 INFO - PID 1692 | Values: 92.0 89.0 93.0 88.0 90.0 89.0 89.0 90.0 89.0 88.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:96.00 stddev:18.58 (19.4%) stddev-sans-first:19.38 03:53:16 INFO - PID 1692 | Values: 113.0 154.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 97.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:90.00 stddev:2.45 (2.7%) stddev-sans-first:2.57 03:53:16 INFO - PID 1692 | Values: 90.0 89.0 89.0 90.0 96.0 90.0 90.0 94.0 93.0 89.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:71.50 stddev:13.94 (19.5%) stddev-sans-first:13.84 03:53:16 INFO - PID 1692 | Values: 50.0 49.0 53.0 81.0 77.0 79.0 80.0 66.0 53.0 52.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:8.31 (13.4%) stddev-sans-first:8.56 03:53:16 INFO - PID 1692 | Values: 56.0 55.0 59.0 64.0 60.0 64.0 56.0 64.0 83.0 56.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:85.00 stddev:10.41 (12.3%) stddev-sans-first:10.82 03:53:16 INFO - PID 1692 | Values: 84.0 83.0 86.0 80.0 107.0 84.0 89.0 88.0 111.0 88.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:143.00 stddev:8.44 (5.9%) stddev-sans-first:8.68 03:53:16 INFO - PID 1692 | Values: 134.0 137.0 134.0 141.0 127.0 145.0 146.0 152.0 132.0 151.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:180.50 stddev:3.12 (1.7%) stddev-sans-first:3.30 03:53:16 INFO - PID 1692 | Values: 180.0 183.0 176.0 175.0 184.0 176.0 177.0 181.0 181.0 179.0 03:53:16 INFO - PID 1692 | 03:53:16 INFO - PID 1692 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:67.50 stddev:9.01 (13.3%) stddev-sans-first:9.41 03:53:16 INFO - PID 1692 | Values: 66.0 67.0 64.0 64.0 67.0 69.0 66.0 92.0 81.0 68.0 03:53:16 INFO - PID 1692 | -------- Summary: end -------- 03:53:16 INFO - PID 1692 | 03:53:17 INFO - TEST-INFO | 1692: exit 0 03:53:17 INFO - TEST-OK | kraken | took 90614ms 03:53:17 INFO - SUITE-END | took 1233s 03:53:17 INFO - Completed test suite (00:20:34) 03:53:17 INFO - javascript benchmark 03:53:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8359.782835143085, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3521.31334389794, 3595.370810108232, 3598.6041535431114, 3614.078410005449, 3616.8914932552016, 4868.391451068612, 6131.713108457295, 6297.748090076398, 6307.716152773125, 6362.331955144713, 5415.431379705134, 6135.956887329154, 6196.938030619693, 6294.905576416358, 6296.181496405461, 5442.591805614575, 5463.972680136613, 5480.534154596864, 5500.312460942382, 5574.526165275956, 5360.303160589118, 5403.2705584745945, 5576.247206627114, 5648.943510564883, 5667.801626943058, 5529.889402211933, 5552.500274975251, 5558.610897237195, 5597.104463285875, 5630.324361076677, 5288.735563221823, 5364.785408583652, 5507.03626865301, 5519.806806761784, 5537.280153580049, 4597.873520987379, 5494.587905907033, 5508.6970216638065, 5527.613067085305, 5528.861778455571, 5254.632175747699, 6191.535634827406, 6292.779752708633, 6305.621662700207, 6320.873582528369, 5696.829095127153, 6436.774712885073, 6510.7721229756735, 6685.264620891698, 6795.38841504262, 6965.582065076112, 6969.0940177776565, 6977.965110174468, 6982.825429364256, 6994.4404447644065, 6403.455706264973, 6411.551191416603, 6413.743450262031, 6414.262359828586, 6432.839179020562, 6602.339766023455, 6714.0, 6967.721291148398, 7068.646567671596, 7083.256258092929, 5608.270924779753, 6355.269144048401, 6696.531242813054, 6707.496937729592, 6721.126253587003, 6429.9678501607195, 6814.182298124257, 6902.240753517109, 6924.169099708067, 6927.376536111774, 6311.179546658905, 6347.936520634735, 6770.966145169243, 6794.626295553693, 6807.353301436356, 6445.903311450332, 6480.611163330215, 6721.529492935448, 6834.419074378634, 6849.2465828758795, 5361.919571206435, 5414.864628384322, 5417.539509141767, 5429.945700542944, 5456.536194423439, 4836.032793441326, 4849.854504364875, 4864.82973095947, 4868.269759536098, 4872.731999740048, 6231.688415579203, 6260.624362538262, 6315.5894866833505, 6335.588186767845, 6404.615723056539, 6914.896276555855, 6938.757143499954, 6981.092457980531, 6985.720571177198, 6988.93011069893, 6384.042393640991, 6391.07329437229, 6401.9039714404325, 6407.519436042222, 6413.3266007069515, 5764.337101233328, 5818.0981947798155, 6052.848678782978, 6177.135201071854, 6183.165272688219, 6994.755183568551, 7068.0, 7084.36240738336, 7098.36114749675, 7101.325374089453, 6926.06498122747, 6927.930720692729, 6939.167299924042, 6950.860982780215, 6976.4767642426, 6672.399484046459, 6920.30796920304, 7007.334303241184, 7023.05188799526, 7051.717931282897, 5714.79998200061, 6392.840178995562, 6723.865522689616, 6786.083878676315, 6849.452043836581, 6872.072270243453, 6929.7228110875, 6932.168139823253, 6937.0, 6971.98906158594, 6375.585586936834, 6419.614823110628, 6659.833504162241, 6714.294999025032, 6723.361280678328, 6612.272650008399, 6677.6661166940485, 6680.3987641112535, 6708.966455167693, 6735.595864248161, 6463.741450341934, 6557.770478033437, 6567.179102612174, 6590.373914478117, 6591.736330546916, 6341.175647165839, 6400.295967443671, 6678.83302917431, 6683.565568238049, 6711.765088221889, 6423.261324947691, 6460.353964603502, 6498.155239818793, 6510.641914694548, 6526.5105117117355], "value": 6175.447182471213, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.9961100194498, 810.4893916832394, 836.560805577072, 1075.7364445710803, 1236.2211806561866, 37911.0, 39231.60768392323, 39311.606883931185, 39344.409833852515, 39424.21151576967, 39569.01077473065, 40007.199856002866, 40162.19675606486, 40180.59819401805, 40239.0, 7819.178986206452, 7983.161768014362, 8174.26431621154, 8374.330053595728, 8410.873836892451, 7261.782146535613, 8314.792130196733, 8382.916170838305, 8407.621657025418, 8408.033076196254, 8314.251717345438, 8344.3741719371, 8393.53835539045, 8394.24451799338, 8408.0, 8337.291330236938, 8346.540940248284, 8378.874316885222, 8397.118302578263, 8402.915970840275, 8205.58972051395, 8251.257386835214, 8251.876221856677, 8254.876176857351, 8308.54303013334, 37323.44014839779, 37499.0, 37560.43659345112, 37610.8119459401, 37659.811700941595, 3634.1459756957356, 3659.4876717259604, 3672.338978983782, 3674.375356189454, 3676.7242456815848, 30353.089407317817, 30479.085627431214, 30493.69506304931, 30551.694483055107, 30684.2328941776, 3077.092257783959, 3090.922726931822, 3100.51941948996, 3132.561441398206, 3133.9216519587194, 241338.58661413335, 243518.0, 269353.6532317345, 299013.0, 299448.0, 48980.0, 49065.0, 49081.75459122753, 49120.0, 49357.0, 50115.0, 50577.49422505728, 50776.492235077916, 50778.49221507811, 50962.23556646653, 14645.974781765388, 14671.55985320442, 14771.187584682946, 14798.186099764616, 14819.77770333446, 11689.298642081332, 11692.824607630891, 11718.23831450953, 11755.118366122575, 11811.586594469152, 12763.361831908367, 13418.597442076752, 14293.928530357281, 14297.999140060096, 14338.283085845665, 4910.582600478928, 5700.6888415664625, 5784.21913041734, 5953.880922381528, 6311.526635502356, 980.7548112971757, 1002.8144793213279, 1004.8342023566175, 1051.7064011266032, 1054.651964851597, 23179.420514486934, 23181.02213023389, 23228.419289517897, 24013.75986240115, 24278.75721242765, 1000.3047881722279, 1086.2884810449168, 1094.578587243901, 1131.688785583971, 1140.680609429361, 11741.530338786524, 11895.286282823059, 12030.4586293617, 12449.532630990649, 12470.376481176087, 294339.0, 297024.0, 297078.0, 297351.0, 297655.0, 42683.0, 54415.72792136093, 54757.178642320396, 55046.72476637591, 55060.72469637706, 1501.5795577238173, 1503.5263891874242, 1540.8998415102983, 1540.9691806163814, 1555.3078879898562, 22873.771262287828, 22876.85615719174, 22938.42653933665, 24020.34160413132, 24089.397765056015, 38136.61863381442, 38179.80910095432, 38433.807830960664, 38447.03882402962, 38672.0, 254050.0, 254433.0, 254908.0, 255872.0, 255998.0, 36288.63711362958, 36421.635783641825, 36431.0, 36487.27025459529, 38025.049373765876, 43102.92242693958, 44826.55173448224, 45512.772436138934, 45617.85955351143, 45659.0, 29333.973310934867, 29863.55204671975, 30061.248468788453, 30214.395712086076, 31497.897573584814, 21361.67957480608, 30273.904855154073, 30912.0, 31423.84288078545, 31569.36861262716, 35903.46144807882, 37155.44266835946, 37690.811545943194, 37808.62191378051, 37891.81054094822, 30260.0, 31035.0, 31226.063218103493, 31777.682223178395, 31876.840615796773, 22708.432289192904, 24409.877950610135, 24583.631245530974, 24591.38521536976, 24604.876975615007], "value": 17428.239653882472, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.62299029747095, 502.6707506583189, 503.1220520192265, 519.8466452396542, 527.1565495207666, 21391.893040534775, 21467.0, 22206.00072996715, 22272.88863555682, 24376.878115609397, 24797.752022479814, 25499.0, 25509.0, 25541.74458255417, 25566.360840978985, 6987.161540615133, 7161.0, 7164.570125792443, 7177.2463891291445, 7179.533330333537, 6790.762323318685, 6841.760538381158, 6922.376986071253, 7022.894656580155, 7068.151821781367, 6103.45068943795, 6904.620245886475, 6910.689018994132, 6914.446844252448, 6931.064306318633, 6891.414229790474, 6900.5859648421, 6917.446604271646, 6924.653767311639, 6930.896036559456, 6780.389764921131, 6785.830354241183, 6786.694598743068, 6793.524453288272, 6795.3204679531655, 30374.87375883689, 31081.22296942572, 31155.376892462024, 31170.220744481343, 31502.212444689067, 2309.307207837659, 2350.3654013416453, 2352.1532248390567, 2352.176738141654, 2360.091364824538, 18267.81732182688, 18437.985910774903, 18741.531461713428, 18806.059697015233, 18822.81177188238, 2085.728855248823, 2106.3996760923214, 2161.5352699169753, 2174.6846707227523, 2222.355516900097, 198344.0, 202434.9878250614, 214077.0, 214584.92707536518, 215824.0, 13254.138480998898, 14088.506902258372, 14584.90613204014, 14802.925985370219, 15008.874334424961, 20553.794462055488, 20577.588448230952, 20580.0, 20584.79415205859, 20662.690059649118, 8623.698170564001, 9035.683751068682, 9732.659356922473, 9791.657291994747, 10202.642907498202, 9265.305102117369, 9270.35107542479, 9280.0, 9309.581068851783, 9340.53297335144, 7601.961990190013, 9175.495347755805, 9232.723018309462, 9292.72121836346, 9295.535223238947, 2235.966460503094, 2476.3561474016888, 2655.4556315955183, 2677.3039009857575, 2804.845733484637, 929.3958926697701, 948.3645957208687, 969.5443141723379, 978.4618459847198, 986.1371300112402, 22146.335609931728, 22180.223692170697, 22221.444463888532, 22301.888490557445, 22487.55024899493, 1005.266145925104, 1033.5504055735778, 1046.340805292676, 1047.4710271312938, 1052.9838705649086, 10486.846446890835, 11149.944250278697, 11195.832062519068, 11519.942400287944, 11658.06735461178, 527164.0, 531125.0, 534548.6545134499, 537827.0, 541670.0, 153657.0, 159683.0, 161028.0, 161692.0, 161836.19081904515, 1205.0841360566033, 1207.3661327802974, 1256.1772039314264, 1261.426051146726, 1263.9873601264053, 22158.556828863653, 22175.55648886981, 23094.960726767, 23414.882925585945, 23778.048878045378, 66996.0, 71692.64153679198, 72904.63547682228, 73052.0, 73860.0, 96114.0, 97088.0, 97167.57081073029, 97443.53834692344, 97502.0, 9703.466309353053, 11732.41337933117, 11790.41047947582, 11809.940950295537, 11840.0, 17495.912520437316, 18699.906500467412, 18920.905395472935, 18954.43136705901, 18989.905050475212, 15254.923725381303, 15263.542093737206, 15414.075155490706, 15446.768298475756, 15455.227238637799, 13633.318334083455, 13651.863481365059, 13839.930800345934, 13905.930470347583, 13933.58199254024, 15507.612309692347, 15516.22418879074, 15735.213239338216, 15821.841781582038, 15888.443904463289, 11403.942980285046, 11565.248258863146, 11676.241044332091, 11728.296302221894, 11742.530298787953, 14783.926080369529, 14836.035657682216, 15018.474353397578, 15038.248087595795, 15042.924785376441], "value": 12059.6301260182, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.195531926588, 1443.6246575890266, 1444.0397135904618, 1446.2913172545457, 1460.9196494192815, 12896.161749486277, 12995.0, 13546.39041243144, 13560.932195339023, 13818.101823381474, 13517.188968661887, 13688.931555342235, 13752.037357384987, 13770.931145344259, 13784.17294962303, 2577.0464927976627, 2615.411532405203, 2624.278323461054, 2626.120249716347, 2628.2509484796838, 2662.454196889633, 2712.4846279206986, 2715.592661100841, 2716.0629582793904, 2723.4553089382102, 2663.853488058156, 2686.3418462476625, 2687.341601307682, 2690.4081102157493, 2693.2593536777445, 2641.9471610567775, 2661.5741481362984, 2665.640138581286, 2668.239551727768, 2672.465506898628, 2614.5293847107705, 2632.3945492536823, 2635.3806855388966, 2721.8911243550233, 2725.11433784021, 13157.342132893413, 13211.075224734272, 13211.339433028406, 13218.60344189676, 13233.801492977516, 7235.927640723578, 7544.396448284124, 7546.169921308651, 7563.130240022413, 7584.976028236173, 4319.654427645813, 4477.126960242754, 4518.344839998182, 4544.0, 4562.133194693031, 1365.4128724648397, 1388.9166650001032, 1407.0924253856308, 1412.1103704666098, 1416.05832121639, 18473.445796625987, 19132.90433547837, 19909.900450497655, 20114.39656810298, 20115.698264526043, 6431.678416079178, 6479.481641468766, 6723.092382528296, 6729.25978142404, 6792.45660347171, 14106.788398174036, 14127.929360353339, 14139.64650883736, 14146.36341364641, 14158.0, 7314.817129571697, 7424.294692004251, 7429.888551671729, 7441.181470038291, 7446.73936412223, 5745.368009518949, 6678.465722742169, 6769.593824370455, 6813.659317034128, 6837.111175547247, 4538.750368729686, 4973.254011898244, 5069.44236134025, 5122.615803814729, 5155.53600175986, 1450.3610919660716, 1477.958039582092, 1479.4747864507945, 1499.5051632961315, 1500.0399744163744, 826.6362800367939, 836.7908022994252, 855.4140413816556, 861.1990848510945, 892.6920212526668, 6758.695858686371, 6761.4590832733265, 7202.49582529218, 8006.0, 8262.628181731836, 603.1856993059334, 647.2491909385091, 698.4796326736614, 703.5637904499141, 706.7455715942359, 4337.132573485315, 4797.520247975174, 4813.711177329302, 5024.547790698782, 5203.297554830049, 17862.642747145244, 18460.538486537946, 18531.35140270111, 18579.0, 18607.0, 18043.18805653749, 18472.722909156375, 18484.630307394047, 18489.0, 18518.16668249932, 8168.632411541495, 8261.504309741435, 9046.321525885585, 9131.936288351753, 9509.66716164931, 7628.656710448154, 8103.756887293391, 8169.5506747129475, 8214.096449390443, 8515.0, 14357.784633230718, 14577.85422145765, 14579.979401441797, 14626.634334141307, 14869.628259293604, 13641.56763539814, 13983.370748316147, 14846.480373186887, 14972.251387430368, 15159.166245856584, 8959.596818143069, 9049.411788233743, 9654.565544550653, 9694.127528522467, 9717.27120465982, 12309.0, 12371.319577422968, 12415.503379864704, 12429.875701242872, 12452.31512266834, 9830.55762490704, 9923.354981926152, 10215.489225538842, 10273.537690804082, 10339.534720937425, 8137.267645911897, 9577.0, 9904.158146557624, 9927.305088643725, 9938.552765125442, 8886.511241881759, 9065.0, 9065.546722663972, 9074.31942604294, 9103.953045399863, 6335.683215839282, 6470.158879345655, 6679.866402672016, 6894.253628161785, 6905.309469053255, 5388.838334849961, 6811.773880701521, 6905.37851593359, 7155.713771449084, 7237.059182306267], "value": 5930.479236076004, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.34580521503005, 381.27938196808054, 395.20958083832335, 454.22781271837874, 455.8791920141163, 6055.697215139247, 6609.636469994149, 6627.9337206627915, 6673.6329501877435, 6713.7985860424205, 5062.696238225709, 6124.387561243873, 6235.968820155893, 6483.1571895653615, 6526.869462610745, 4146.771927543984, 4791.952080479195, 4815.614750819944, 4816.736134017024, 4867.561919427251, 4158.0, 4654.952635656967, 4732.911430371017, 4826.203676393391, 4846.709197448146, 4781.37842080529, 4796.856094317176, 4800.807967681289, 4802.255650374198, 4810.542998415145, 4798.352222449995, 4813.157697402976, 4817.735024573647, 4834.06024090367, 4844.15227335215, 4236.279832428495, 4242.215190189825, 4249.596288352602, 4250.277452833026, 4253.489581250239, 6401.039844023388, 6406.4234218920055, 6438.967805160944, 6460.93539064608, 6476.215227452194, 4865.635077369211, 5528.834134975958, 5750.166225897264, 5761.279840019997, 5814.389489103625, 6499.642519661416, 6904.551204171763, 6975.8604827903155, 6993.860122797516, 7001.264867188923, 4476.194285028693, 4486.214912390351, 4805.783739731721, 4846.418429788448, 4854.0, 6547.934520654732, 7038.824029399255, 7244.492885498017, 7314.975903373532, 7423.851522969564, 4683.765811709401, 4841.782119804618, 4877.878053048702, 4900.313956046157, 4950.737561921759, 4178.791060446965, 4493.415855938772, 5012.298278241049, 5014.172661510883, 5023.296738456546, 5441.428649991773, 5609.747561359749, 5618.157276408489, 5695.743691533891, 5709.68596727184, 5288.497592728761, 6221.889055472264, 6929.480288978246, 6983.580985140908, 7015.263397343308, 1457.7959085728007, 1682.973386234396, 1702.8722845786615, 1730.4981555348809, 1732.3936622182387, 951.5052172870069, 1035.254616675992, 1162.0761494611804, 1162.151629310608, 1163.040491594438, 6500.512461565401, 6511.804645860537, 6525.869482610321, 6551.934480655228, 6581.40767330933, 7231.204567497571, 7250.1299844018, 7255.637218139072, 7262.600556969312, 7264.963675181696, 5477.890442191134, 6513.413792758674, 6516.706748196342, 6525.934740652628, 6540.9345906541275, 3384.61076976146, 3733.738638295293, 3777.3002149033045, 3807.466954626299, 3822.5604055533413, 6646.6676666166495, 6937.028815965768, 7407.333339999426, 7411.481196316314, 7464.365528930101, 6810.8297292567095, 7388.931099292099, 7486.588237646982, 7492.7636939904305, 7511.549307041595, 6405.743770249231, 6624.701888415033, 7361.889571656537, 7394.667239974214, 7438.776836694799, 7175.46184036189, 7187.6765545551625, 7211.42308615299, 7245.311695388929, 7247.2390399007345, 6625.900611490928, 7077.442962548231, 7099.183593886769, 7138.286171382821, 7198.5680859148615, 6762.357576030269, 7392.297731715478, 7426.220246874216, 7473.028506294147, 7562.621868906643, 4678.157931572349, 4871.610271178368, 4904.558589726942, 4930.654854160174, 4931.052234367477, 6074.544409169242, 6651.334866513504, 6942.722291108299, 7273.890252007599, 7311.3785328247695, 6926.757563485254, 6957.304269573002, 6967.247937064933, 6967.512274140761, 6969.337912898267, 6956.0, 6981.650917454209, 7002.984567237824, 7019.19279282889, 7033.964830175816, 6485.708143133476, 6504.772332968323, 6534.281229064894, 6779.93220067793, 6884.827879303058, 6513.567015256636, 6526.510511711545, 6931.09895713554, 6949.0, 6952.721891124298, 6750.05499230118, 6767.492438067263, 6774.220964589135, 6777.796666100025, 6781.864362712817], "value": 5115.943155617752, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.1571981520789, 810.9351251899849, 823.0945959444612, 843.586642545153, 851.0340763233728, 14843.851561484382, 15261.160636165007, 16845.49463516095, 17401.868878522888, 17442.91278543607, 16282.348706051744, 17838.286468541242, 18142.002189879553, 18159.54601134972, 18217.453476395694, 978.8238117138912, 996.3822430093317, 1012.9392236465799, 1017.460745804721, 1017.6031347774336, 998.7153661206557, 1013.761765984993, 1014.4978235773266, 1015.9238057145707, 1026.1841835740604, 943.702733638905, 1043.5825669732092, 1046.4192373232852, 1052.1319911073394, 1078.4553800330823, 1020.6274709730932, 1030.6959446963165, 1038.7091614347958, 1042.6715584590906, 1049.9002594753485, 761.756238003839, 770.6647608290411, 774.7094839435211, 775.8176828445311, 782.9138794732575, 16842.578935526588, 16987.91506042474, 17155.228014739365, 17177.96932184073, 17407.47777566675, 16380.918095409566, 16445.506634800975, 16804.41184558547, 16876.746848797276, 16887.0, 14135.505257316048, 14472.855271447255, 14507.782383264259, 14616.70766584673, 14627.414903403851, 3589.0668426209113, 3609.097725568608, 3609.151849315409, 3614.331348700499, 3615.2407994321225, 145605.2719736405, 147155.52844471525, 147974.26012869974, 149227.0, 149849.0, 12592.496300147892, 12745.04412169091, 13564.457421703219, 13573.253471059186, 13792.517261895786, 22666.773332266464, 26119.216423507325, 26685.733142668323, 26829.597556036675, 26859.865700671373, 6697.832554186087, 7536.585487798224, 7658.65536050879, 7798.064232292161, 7824.061112666516, 3831.5593706724085, 3913.0608653922855, 3923.43110249018, 3923.6664883484655, 3929.8821035368414, 4460.50934397216, 4510.9173798288175, 4535.705179163344, 4555.521670224645, 4571.177188106173, 900.7704483380139, 905.3798148268457, 921.1157289002632, 921.4517362169632, 923.3905622289257, 627.3036929008797, 645.8772833161778, 647.685872351909, 663.93692599203, 675.1425689374565, 11225.101991840633, 11275.84861490444, 11735.88264117365, 11934.28394296345, 12258.44836982338, 651.17951381259, 659.1200747002805, 667.1360588038502, 678.657278074579, 692.9688164032631, 5602.943970560243, 5642.548596112301, 5738.110592858114, 5778.728679690461, 5967.970160149258, 113523.43238283756, 114995.42502287435, 116959.41520292344, 118081.40959295149, 124883.37558312331, 96879.51560242295, 97823.0, 104100.47949760202, 104353.26057066934, 105467.41798873023, 88907.66638500428, 90267.64598531027, 93331.0, 96886.51556742312, 98716.0, 10784.352938823695, 10948.069414099891, 11024.456576079196, 11542.826857596974, 12026.518939242333, 41056.58943410647, 44141.0, 44192.55807441884, 44223.33664995092, 44740.0, 65545.34454655393, 66943.0, 71775.92336115066, 72670.27329726635, 77095.0, 16312.347506099622, 16859.072750998814, 16904.830951690325, 16952.491425257263, 17057.317707292066, 19088.713669295248, 19282.0, 19312.0, 19348.0, 19424.125914333606, 16504.25730842139, 17554.824451755318, 17998.280068797103, 18223.088845557933, 18224.0, 15251.237438128272, 17752.822471775635, 17850.732239016164, 18162.182701778715, 18339.0, 17727.379541715978, 17795.46613601594, 17805.910970445064, 17911.820881791016, 18034.909825450788, 11250.324980501196, 11722.58970936013, 11874.109441792072, 12222.816657750318, 12402.689932751753, 12316.815247771112, 12499.375031248583, 13170.736585268432, 13231.3384330785, 13339.866601333862], "value": 8667.03945821424, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 151.0, 103.0, 163.0, 172.0, 101.0, 161.0, 167.0, 158.0, 165.0], "value": 145.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 106.0, 121.0, 186.0, 144.0, 140.0, 129.0, 111.0, 169.0, 102.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 208.0, 206.0, 228.0, 216.0, 268.0, 271.0, 197.0, 199.0, 197.0], "value": 218.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 96.0, 110.0, 187.0, 49.0, 78.0, 85.0, 87.0, 168.0, 60.0], "value": 99.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 115.0, 73.0, 71.0, 83.0, 107.0, 70.0, 69.0, 107.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 93.0, 88.0, 90.0, 89.0, 89.0, 90.0, 89.0, 88.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 154.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 97.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 90.0, 96.0, 90.0, 90.0, 94.0, 93.0, 89.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 53.0, 81.0, 77.0, 79.0, 80.0, 66.0, 53.0, 52.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 59.0, 64.0, 60.0, 64.0, 56.0, 64.0, 83.0, 56.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 86.0, 80.0, 107.0, 84.0, 89.0, 88.0, 111.0, 88.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 134.0, 141.0, 127.0, 145.0, 146.0, 152.0, 132.0, 151.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 176.0, 175.0, 184.0, 176.0, 177.0, 181.0, 181.0, 179.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 64.0, 64.0, 67.0, 69.0, 66.0, 92.0, 81.0, 68.0], "value": 70.4, "unit": "score"}]}]} 03:53:17 INFO - Return code: 0 03:53:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:53:17 INFO - # TBPL SUCCESS # 03:53:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:53:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:53:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:53:17 INFO - 2017-06-10 03:53:17.781 screenresolution[1700:11727] starting screenresolution argv=screenresolution get 03:53:17 INFO - 2017-06-10 03:53:17.784 screenresolution[1700:11727] Display 0: 1600x1200x32@60 03:53:17 INFO - 2017-06-10 03:53:17.790 screenresolution[1701:11730] starting screenresolution argv=screenresolution list 03:53:17 INFO - Available Modes on Display 0 03:53:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:53:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:53:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:53:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:53:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:53:18 INFO - Intel Iris: 03:53:18 INFO - Chipset Model: Intel Iris 03:53:18 INFO - Type: GPU 03:53:18 INFO - Bus: Built-In 03:53:18 INFO - VRAM (Dynamic, Max): 1536 MB 03:53:18 INFO - Vendor: Intel (0x8086) 03:53:18 INFO - Device ID: 0x0a2e 03:53:18 INFO - Revision ID: 0x0009 03:53:18 INFO - Displays: 03:53:18 INFO - Display: 03:53:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:53:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:53:18 INFO - Display Serial Number: 1600x1200 60 03:53:18 INFO - Main Display: Yes 03:53:18 INFO - Mirror: Off 03:53:18 INFO - Online: Yes 03:53:18 INFO - Rotation: Supported 03:53:18 INFO - Return code: 0 03:53:18 INFO - Running post-action listener: _resource_record_post_action 03:53:18 INFO - [mozharness: 2017-06-10 10:53:18.032654Z] Finished run-tests step (success) 03:53:18 INFO - Running post-run listener: _resource_record_post_run 03:53:18 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 34522112; Write bytes: 572663296; Read time: 229; Write time: 1770 03:53:18 INFO - TinderboxPrint: CPU usage
53.3% 03:53:18 INFO - TinderboxPrint: I/O read bytes / time
34,522,112 / 229 03:53:18 INFO - TinderboxPrint: I/O write bytes / time
572,663,296 / 1,770 03:53:18 INFO - TinderboxPrint: CPU idle
2,348.0 (46.7%) 03:53:18 INFO - TinderboxPrint: CPU system
1,018.2 (20.3%) 03:53:18 INFO - TinderboxPrint: CPU user
1,659.5 (33.0%) 03:53:18 INFO - TinderboxPrint: Swap in / out
283,185,152 / 0 03:53:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 251546112; Write bytes: 246257152; Read time: 18048; Write time: 536 03:53:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:53:18 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 30196736; Write bytes: 316076032; Read time: 203; Write time: 1196 03:53:18 INFO - Running post-run listener: _upload_blobber_files 03:53:18 INFO - Blob upload gear active. 03:53:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:53:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:53:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:53:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:53:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:19 INFO - (blobuploader) - INFO - Done attempting. 03:53:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:53:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:53:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:20 INFO - (blobuploader) - INFO - Done attempting. 03:53:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:53:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:53:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:21 INFO - (blobuploader) - INFO - Done attempting. 03:53:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:53:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:53:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:24 INFO - (blobuploader) - INFO - Done attempting. 03:53:24 INFO - (blobuploader) - INFO - Iteration through files over. 03:53:24 INFO - Return code: 0 03:53:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:53:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:53:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a84aa00272cc20508463e4bc635ee97ab5b458bea142c1f357cb0edb9930ef4711654849227cdb61be302729efcab46cf176a0eed797ca6fc9615b255a609a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68f7e7f5516b5bca9fa2fd8f186c684d38f48ca12d4f951f321ab7951c46a717759bfff8b016b1653fd54d87aeada1e181601f4c5ecedad4b482a29706ed5bcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95a90e7e4eca1c4c4054f29f9f07a3efc4cf971492b224bf37b08f6c9a4a383042b01b2f3bae79f442a78d87f7205ea0b8f2dda0091692e17f6b5b2bc7d00d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e15dda352de12c13eeb2f2ee7fd11bbe429b6b5243a026c1850bf85e9e8b565c93bcf3269fbcf8532a424afcdc500447c68f9a07cea43bdef64a815ca9e548b"} 03:53:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:53:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:53:24 INFO - Contents: 03:53:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a84aa00272cc20508463e4bc635ee97ab5b458bea142c1f357cb0edb9930ef4711654849227cdb61be302729efcab46cf176a0eed797ca6fc9615b255a609a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68f7e7f5516b5bca9fa2fd8f186c684d38f48ca12d4f951f321ab7951c46a717759bfff8b016b1653fd54d87aeada1e181601f4c5ecedad4b482a29706ed5bcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95a90e7e4eca1c4c4054f29f9f07a3efc4cf971492b224bf37b08f6c9a4a383042b01b2f3bae79f442a78d87f7205ea0b8f2dda0091692e17f6b5b2bc7d00d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e15dda352de12c13eeb2f2ee7fd11bbe429b6b5243a026c1850bf85e9e8b565c93bcf3269fbcf8532a424afcdc500447c68f9a07cea43bdef64a815ca9e548b"} 03:53:24 INFO - Running post-run listener: copy_logs_to_upload_dir 03:53:24 INFO - Copying logs to upload dir... 03:53:24 INFO - mkdir: /builds/slave/test/build/upload/logs 03:53:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.386066 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-10 03:53:24.171160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 03:53:24.174775) ========= 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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/0a84aa00272cc20508463e4bc635ee97ab5b458bea142c1f357cb0edb9930ef4711654849227cdb61be302729efcab46cf176a0eed797ca6fc9615b255a609a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68f7e7f5516b5bca9fa2fd8f186c684d38f48ca12d4f951f321ab7951c46a717759bfff8b016b1653fd54d87aeada1e181601f4c5ecedad4b482a29706ed5bcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95a90e7e4eca1c4c4054f29f9f07a3efc4cf971492b224bf37b08f6c9a4a383042b01b2f3bae79f442a78d87f7205ea0b8f2dda0091692e17f6b5b2bc7d00d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e15dda352de12c13eeb2f2ee7fd11bbe429b6b5243a026c1850bf85e9e8b565c93bcf3269fbcf8532a424afcdc500447c68f9a07cea43bdef64a815ca9e548b"} build_url:https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/CNckeSrRQieOuwrEnGgyfQ/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/0a84aa00272cc20508463e4bc635ee97ab5b458bea142c1f357cb0edb9930ef4711654849227cdb61be302729efcab46cf176a0eed797ca6fc9615b255a609a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68f7e7f5516b5bca9fa2fd8f186c684d38f48ca12d4f951f321ab7951c46a717759bfff8b016b1653fd54d87aeada1e181601f4c5ecedad4b482a29706ed5bcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95a90e7e4eca1c4c4054f29f9f07a3efc4cf971492b224bf37b08f6c9a4a383042b01b2f3bae79f442a78d87f7205ea0b8f2dda0091692e17f6b5b2bc7d00d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e15dda352de12c13eeb2f2ee7fd11bbe429b6b5243a026c1850bf85e9e8b565c93bcf3269fbcf8532a424afcdc500447c68f9a07cea43bdef64a815ca9e548b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 03:53:24.207784) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:53:24.208125) ========= 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.JeZRSNY2d9/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.kb6R7WjUbH/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005196 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 03:53:24.257204) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 03:53:24.257525) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 03:53:24.257867) ========= ========= Total master_lag: 0.17 =========