builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0105 starttime: 1496799886.08 results: success (0) revision: 5801aa478de12a62b2b2982659e787fcc4268d67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.078113) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.078563) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.078849) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.104845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.105315) ========= 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.YpQImPSbVC/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.OB9fqE6Edp/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-06 18:44:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2017-06-06 18:44:46 (15.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.261621) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.261973) ========= 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.YpQImPSbVC/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.OB9fqE6Edp/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.028149 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:46.311077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 18:44:46.311415) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5801aa478de12a62b2b2982659e787fcc4268d67 --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 5801aa478de12a62b2b2982659e787fcc4268d67 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YpQImPSbVC/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.OB9fqE6Edp/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-06 18:44:46,381 truncating revision to first 12 chars 2017-06-06 18:44:46,381 Setting DEBUG logging. 2017-06-06 18:44:46,381 attempt 1/10 2017-06-06 18:44:46,381 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5801aa478de1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 18:44:47,820 unpacking tar archive at: mozilla-central-5801aa478de1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.716748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 18:44:48.050459) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:48.050835) ========= script_repo_revision: 5801aa478de12a62b2b2982659e787fcc4268d67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:48.051266) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:48.051535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 18:44:48.070872) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-06 18:44:48.071366) ========= /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.YpQImPSbVC/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.OB9fqE6Edp/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:44:48 INFO - MultiFileLogger online at 20170606 18:44:48 in /builds/slave/test 18:44:48 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 18:44:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:44:48 INFO - {'append_to_log': False, 18:44:48 INFO - 'base_work_dir': '/builds/slave/test', 18:44:48 INFO - 'blob_upload_branch': 'Firefox', 18:44:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:44:48 INFO - 'branch': 'Firefox', 18:44:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:44:48 INFO - 'config_files': ('talos/mac_config.py',), 18:44:48 INFO - 'default_actions': ('clobber', 18:44:48 INFO - 'read-buildbot-config', 18:44:48 INFO - 'download-and-extract', 18:44:48 INFO - 'populate-webroot', 18:44:48 INFO - 'create-virtualenv', 18:44:48 INFO - 'install', 18:44:48 INFO - 'setup-mitmproxy', 18:44:48 INFO - 'run-tests'), 18:44:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:44:48 INFO - 'download_minidump_stackwalk': True, 18:44:48 INFO - 'download_symbols': 'ondemand', 18:44:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:44:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:44:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:44:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:44:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:44:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:44:48 INFO - 'gecko_profile': False, 18:44:48 INFO - 'gecko_profile_interval': 0, 18:44:48 INFO - 'installer_path': 'installer.exe', 18:44:48 INFO - 'log_level': 'info', 18:44:48 INFO - 'log_name': 'talos', 18:44:48 INFO - 'log_to_console': True, 18:44:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:44:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:44:48 INFO - 'opt_config_files': (), 18:44:48 INFO - 'pip_index': False, 18:44:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:44:48 INFO - 'cmd': ('bash', 18:44:48 INFO - '-c', 18:44:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:44:48 INFO - 'enabled': True, 18:44:48 INFO - 'halt_on_failure': False, 18:44:48 INFO - 'name': 'check_screen_resolution'},), 18:44:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:44:48 INFO - 'cmd': ('bash', 18:44:48 INFO - '-c', 18:44:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:44:48 INFO - 'enabled': True, 18:44:48 INFO - 'halt_on_failure': False, 18:44:48 INFO - 'name': 'check_screen_resolution'},), 18:44:48 INFO - 'run_cmd_checks_enabled': True, 18:44:48 INFO - 'suite': 'dromaeojs-e10s', 18:44:48 INFO - 'system_bits': '32', 18:44:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:44:48 INFO - 'title': 't-yosemite-r7-0105', 18:44:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:44:48 INFO - 'use_talos_json': True, 18:44:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:44:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:44:48 INFO - 'work_dir': 'build'} 18:44:48 INFO - [mozharness: 2017-06-07 01:44:48.260682Z] Running clobber step. 18:44:48 INFO - Running pre-action listener: _resource_record_pre_action 18:44:48 INFO - Running main action method: clobber 18:44:48 INFO - rmtree: /builds/slave/test/build 18:44:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:44:50 INFO - Running post-action listener: _resource_record_post_action 18:44:50 INFO - [mozharness: 2017-06-07 01:44:50.781482Z] Finished clobber step (success) 18:44:50 INFO - [mozharness: 2017-06-07 01:44:50.781607Z] Running read-buildbot-config step. 18:44:50 INFO - Running pre-action listener: _resource_record_pre_action 18:44:50 INFO - Running main action method: read_buildbot_config 18:44:50 INFO - Using buildbot properties: 18:44:50 INFO - { 18:44:50 INFO - "project": "", 18:44:50 INFO - "product": "firefox", 18:44:50 INFO - "who": "kwierso@gmail.com", 18:44:50 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:44:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:44:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:44:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:44:50 INFO - "stage_platform": "macosx64", 18:44:50 INFO - "basedir": "/builds/slave/test", 18:44:50 INFO - "buildnumber": 94, 18:44:50 INFO - "platform": "yosemite_r7", 18:44:50 INFO - "parent_task_id": "TX0BylbVTBeK15F7jyDbZQ", 18:44:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:44:50 INFO - "slavebuilddir": "test", 18:44:50 INFO - "taskId": "M2UNaDp9RRW0UZkNvQlEbQ", 18:44:50 INFO - "branch": "mozilla-central", 18:44:50 INFO - "script_repo_revision": "production", 18:44:50 INFO - "revision": "5801aa478de12a62b2b2982659e787fcc4268d67", 18:44:50 INFO - "slavename": "t-yosemite-r7-0105", 18:44:50 INFO - "repo_path": "mozilla-central" 18:44:50 INFO - } 18:44:50 INFO - Finding installer, test and symbols from parent task. 18:44:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2UNaDp9RRW0UZkNvQlEbQ'}, attempt #1 18:44:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2UNaDp9RRW0UZkNvQlEbQ'}, attempt #1 18:44:52 INFO - Task dependencies: Go6u-W1XR6a6WQKewH6eNw TX0BylbVTBeK15F7jyDbZQ 18:44:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ'}, attempt #1 18:44:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:44:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/test_packages.json 18:44:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/target.crashreporter-symbols.zip 18:44:52 INFO - Running post-action listener: _resource_record_post_action 18:44:52 INFO - [mozharness: 2017-06-07 01:44:52.544950Z] Finished read-buildbot-config step (success) 18:44:52 INFO - [mozharness: 2017-06-07 01:44:52.545061Z] Running download-and-extract step. 18:44:52 INFO - Running pre-action listener: _resource_record_pre_action 18:44:52 INFO - Running main action method: download_and_extract 18:44:52 INFO - mkdir: /builds/slave/test/build/tests 18:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:52 INFO - trying https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:44:52 INFO - Downloading https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/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 18:44:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/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 18:44:53 INFO - Downloaded 1328 bytes. 18:44:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:44:53 INFO - Using the following test package requirements: 18:44:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:44:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:44:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:44:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:44:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'jsshell-mac.zip'], 18:44:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:44:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:44:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:44:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:44:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 18:44:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:44:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:44:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:44:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:44:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:44:53 INFO - Fetch https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:44:55 INFO - Content-Length response header: 57640097 18:44:55 INFO - Bytes received: 57640097 18:45:13 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 18:45:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:45:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:45:13 INFO - Fetch https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:45:15 INFO - Content-Length response header: 57640097 18:45:15 INFO - Bytes received: 57640097 18:45:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:45:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:45:32 INFO - Fetch https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:45:33 INFO - Content-Length response header: 14447563 18:45:33 INFO - Bytes received: 14447563 18:45:34 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')]} 18:45:34 INFO - trying https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:45:34 INFO - Downloading https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:45:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/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 18:45:36 INFO - Downloaded 68022171 bytes. 18:45:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:45:36 INFO - mkdir: /builds/slave/test/properties 18:45:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:45:36 INFO - Writing to file /builds/slave/test/properties/build_url 18:45:36 INFO - Contents: 18:45:36 INFO - build_url:https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:45:36 INFO - Running post-action listener: _resource_record_post_action 18:45:36 INFO - Running post-action listener: set_extra_try_arguments 18:45:36 INFO - [mozharness: 2017-06-07 01:45:36.057990Z] Finished download-and-extract step (success) 18:45:36 INFO - [mozharness: 2017-06-07 01:45:36.058124Z] Running populate-webroot step. 18:45:36 INFO - Running pre-action listener: _resource_record_pre_action 18:45:36 INFO - Running main action method: populate_webroot 18:45:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:45:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:45:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:45:36 INFO - '--tppagecycles', 18:45:36 INFO - '7'], 18:45:36 INFO - 'tests': ['tsvgm']}}, 18:45:36 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:45:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:45:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:45:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:45:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:45:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['damp', 'tps']}, 18:45:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'tests': ['damp', 'tps']}, 18:45:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['dromaeo_dom']}, 18:45:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:45:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:45:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:45:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:45:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'other-e10s': {'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:45:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:45:36 INFO - 'bloom_basic_ref']}, 18:45:36 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:45:36 INFO - 'talos_options': ['--mitmproxy', 18:45:36 INFO - 'mitmproxy-recording-1.mp', 18:45:36 INFO - '--firstNonBlankPaint'], 18:45:36 INFO - 'tests': ['Quantum_1']}, 18:45:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['tsvgx', 18:45:36 INFO - 'tsvgr_opacity', 18:45:36 INFO - 'tart', 18:45:36 INFO - 'tscrollx', 18:45:36 INFO - 'cart', 18:45:36 INFO - 'tsvg_static']}, 18:45:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:45:36 INFO - 'tsvgr_opacity', 18:45:36 INFO - 'tart', 18:45:36 INFO - 'tscrollx', 18:45:36 INFO - 'cart', 18:45:36 INFO - 'tsvg_static']}, 18:45:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--disable-e10s'], 18:45:36 INFO - 'tests': ['tp5o']}, 18:45:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:45:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--disable-e10s', 18:45:36 INFO - '--xperf_path', 18:45:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:45:36 INFO - 'tests': ['tp5n']}, 18:45:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--xperf_path', 18:45:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:45:36 INFO - 'tests': ['tp5n']}}, 18:45:36 INFO - 'talos.zip': {'path': '', 18:45:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:45:36 INFO - Running post-action listener: _resource_record_post_action 18:45:36 INFO - [mozharness: 2017-06-07 01:45:36.080044Z] Finished populate-webroot step (success) 18:45:36 INFO - [mozharness: 2017-06-07 01:45:36.080158Z] Running create-virtualenv step. 18:45:36 INFO - Running pre-action listener: _resource_record_pre_action 18:45:36 INFO - Running main action method: create_virtualenv 18:45:36 INFO - Creating virtualenv /builds/slave/test/build/venv 18:45:36 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 18:45:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:45:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:45:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:45:36 INFO - Using real prefix '/tools/python27' 18:45:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:45:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:45:40 INFO - Installing pip.................done. 18:45:40 INFO - Return code: 0 18:45:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:45:40 INFO - Reading from file tmpfile_stdout 18:45:40 INFO - Output received: 18:45:40 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) 18:45:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:45:40 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')]} 18:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:40 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')]} 18:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:40 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:40 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 18:45:40 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 18:45:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:40 INFO - 'HOME': '/Users/cltbld', 18:45:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:40 INFO - 'LOGNAME': 'cltbld', 18:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:40 INFO - 'MOZ_NO_REMOTE': '1', 18:45:40 INFO - 'NO_EM_RESTART': '1', 18:45:40 INFO - 'PAGER': '/bin/cat', 18:45:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:40 INFO - 'PWD': '/builds/slave/test', 18:45:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:40 INFO - 'SHELL': '/bin/bash', 18:45:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:40 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:40 INFO - 'USER': 'cltbld', 18:45:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:40 INFO - 'XPC_FLAGS': '0x0', 18:45:40 INFO - 'XPC_SERVICE_NAME': '0', 18:45:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:40 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 18:45:40 INFO - Cleaning up... 18:45:40 INFO - Return code: 0 18:45:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:45:40 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')]} 18:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:40 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')]} 18:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:40 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:40 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 18:45:40 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 18:45:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:40 INFO - 'HOME': '/Users/cltbld', 18:45:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:40 INFO - 'LOGNAME': 'cltbld', 18:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:40 INFO - 'MOZ_NO_REMOTE': '1', 18:45:40 INFO - 'NO_EM_RESTART': '1', 18:45:40 INFO - 'PAGER': '/bin/cat', 18:45:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:40 INFO - 'PWD': '/builds/slave/test', 18:45:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:40 INFO - 'SHELL': '/bin/bash', 18:45:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:40 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:40 INFO - 'USER': 'cltbld', 18:45:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:40 INFO - 'XPC_FLAGS': '0x0', 18:45:40 INFO - 'XPC_SERVICE_NAME': '0', 18:45:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:40 INFO - Downloading/unpacking psutil>=3.1.1 18:45:40 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 18:45:40 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 18:45:40 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 18:45:40 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 18:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:45:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:42 INFO - Installing collected packages: psutil 18:45:42 INFO - Running setup.py install for psutil 18:45:42 INFO - building 'psutil._psutil_osx' extension 18:45:42 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 18:45:43 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 18:45:43 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 18:45:43 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 18:45:43 INFO - building 'psutil._psutil_posix' extension 18:45:43 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 18:45:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:45:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:45:43 INFO - ^ 18:45:43 INFO - 1 warning generated. 18:45:43 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 18:45:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:43 INFO - Successfully installed psutil 18:45:43 INFO - Cleaning up... 18:45:44 INFO - Return code: 0 18:45:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:45:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:44 INFO - 'HOME': '/Users/cltbld', 18:45:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:44 INFO - 'LOGNAME': 'cltbld', 18:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:44 INFO - 'MOZ_NO_REMOTE': '1', 18:45:44 INFO - 'NO_EM_RESTART': '1', 18:45:44 INFO - 'PAGER': '/bin/cat', 18:45:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:44 INFO - 'PWD': '/builds/slave/test', 18:45:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:44 INFO - 'SHELL': '/bin/bash', 18:45:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:44 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:44 INFO - 'USER': 'cltbld', 18:45:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:44 INFO - 'XPC_FLAGS': '0x0', 18:45:44 INFO - 'XPC_SERVICE_NAME': '0', 18:45:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:45:45 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 18:45:45 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 18:45:45 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 18:45:45 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 18:45:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:45:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:45:46 INFO - Installing collected packages: mozsystemmonitor 18:45:46 INFO - Running setup.py install for mozsystemmonitor 18:45:47 INFO - Successfully installed mozsystemmonitor 18:45:47 INFO - Cleaning up... 18:45:47 INFO - Return code: 0 18:45:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:47 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:47 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 18:45:47 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 18:45:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:47 INFO - 'HOME': '/Users/cltbld', 18:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:47 INFO - 'LOGNAME': 'cltbld', 18:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:47 INFO - 'MOZ_NO_REMOTE': '1', 18:45:47 INFO - 'NO_EM_RESTART': '1', 18:45:47 INFO - 'PAGER': '/bin/cat', 18:45:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:47 INFO - 'PWD': '/builds/slave/test', 18:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:47 INFO - 'SHELL': '/bin/bash', 18:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:47 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:47 INFO - 'USER': 'cltbld', 18:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:47 INFO - 'XPC_FLAGS': '0x0', 18:45:47 INFO - 'XPC_SERVICE_NAME': '0', 18:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:47 INFO - Downloading/unpacking jsonschema==2.5.1 18:45:47 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 18:45:47 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 18:45:47 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 18:45:47 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 18:45:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:45:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:49 INFO - Downloading functools32-3.2.3-2.tar.gz 18:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:45:49 INFO - warning: no files found matching '*.txt' 18:45:49 INFO - no previously-included directories found matching 'build' 18:45:49 INFO - no previously-included directories found matching 'dist' 18:45:49 INFO - no previously-included directories found matching '.git*' 18:45:49 INFO - Installing collected packages: jsonschema, functools32 18:45:49 INFO - Running setup.py install for functools32 18:45:49 INFO - warning: no files found matching '*.txt' 18:45:49 INFO - no previously-included directories found matching 'build' 18:45:49 INFO - no previously-included directories found matching 'dist' 18:45:49 INFO - no previously-included directories found matching '.git*' 18:45:49 INFO - Successfully installed jsonschema functools32 18:45:49 INFO - Cleaning up... 18:45:49 INFO - Return code: 0 18:45:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:45:49 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')]} 18:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:49 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')]} 18:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:49 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:49 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 18:45:49 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 18:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:49 INFO - 'HOME': '/Users/cltbld', 18:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:49 INFO - 'LOGNAME': 'cltbld', 18:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:49 INFO - 'MOZ_NO_REMOTE': '1', 18:45:49 INFO - 'NO_EM_RESTART': '1', 18:45:49 INFO - 'PAGER': '/bin/cat', 18:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:49 INFO - 'PWD': '/builds/slave/test', 18:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:49 INFO - 'SHELL': '/bin/bash', 18:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:49 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:49 INFO - 'USER': 'cltbld', 18:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:49 INFO - 'XPC_FLAGS': '0x0', 18:45:49 INFO - 'XPC_SERVICE_NAME': '0', 18:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:45:49 INFO - Cleaning up... 18:45:49 INFO - Return code: 0 18:45:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:45:49 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')]} 18:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:49 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')]} 18:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:49 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:49 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 18:45:49 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 18:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:49 INFO - 'HOME': '/Users/cltbld', 18:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:49 INFO - 'LOGNAME': 'cltbld', 18:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:49 INFO - 'MOZ_NO_REMOTE': '1', 18:45:49 INFO - 'NO_EM_RESTART': '1', 18:45:49 INFO - 'PAGER': '/bin/cat', 18:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:49 INFO - 'PWD': '/builds/slave/test', 18:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:49 INFO - 'SHELL': '/bin/bash', 18:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:49 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:49 INFO - 'USER': 'cltbld', 18:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:49 INFO - 'XPC_FLAGS': '0x0', 18:45:49 INFO - 'XPC_SERVICE_NAME': '0', 18:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:49 INFO - Downloading/unpacking blobuploader==1.2.4 18:45:49 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 18:45:49 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 18:45:49 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 18:45:49 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 18:45:51 INFO - Downloading blobuploader-1.2.4.tar.gz 18:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:45:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:45:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:52 INFO - Downloading docopt-0.6.1.tar.gz 18:45:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:45:52 INFO - Installing collected packages: blobuploader, requests, docopt 18:45:52 INFO - Running setup.py install for blobuploader 18:45:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:45:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:45:52 INFO - Running setup.py install for requests 18:45:52 INFO - Running setup.py install for docopt 18:45:53 INFO - Successfully installed blobuploader requests docopt 18:45:53 INFO - Cleaning up... 18:45:53 INFO - Return code: 0 18:45:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:53 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')]} 18:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:53 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')]} 18:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:53 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:53 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 18:45:53 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 18:45:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:53 INFO - 'HOME': '/Users/cltbld', 18:45:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:53 INFO - 'LOGNAME': 'cltbld', 18:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:53 INFO - 'MOZ_NO_REMOTE': '1', 18:45:53 INFO - 'NO_EM_RESTART': '1', 18:45:53 INFO - 'PAGER': '/bin/cat', 18:45:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:53 INFO - 'PWD': '/builds/slave/test', 18:45:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:53 INFO - 'SHELL': '/bin/bash', 18:45:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:53 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:53 INFO - 'USER': 'cltbld', 18:45:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:53 INFO - 'XPC_FLAGS': '0x0', 18:45:53 INFO - 'XPC_SERVICE_NAME': '0', 18:45:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:45:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-WmG0Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:45:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pp3Rm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:45:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gRlIy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:45:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Occg4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:45:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-4pr3QZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:45:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-OJGcJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-TrDclG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-JWHqUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-jgL0ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-7iOJql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-bLgPtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Q4Qzhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-eGjdmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-rYMwBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-N5tulN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:45:55 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-I2eAkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:45:55 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-a78q9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:45:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:45:55 INFO - Running setup.py install for manifestparser 18:45:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:45:55 INFO - Running setup.py install for mozcrash 18:45:55 INFO - Running setup.py install for mozdebug 18:45:55 INFO - Running setup.py install for mozdevice 18:45:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:45:55 INFO - Running setup.py install for mozfile 18:45:55 INFO - Running setup.py install for mozhttpd 18:45:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Running setup.py install for mozinfo 18:45:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Running setup.py install for mozInstall 18:45:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Running setup.py install for mozleak 18:45:56 INFO - Running setup.py install for mozlog 18:45:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Running setup.py install for moznetwork 18:45:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:45:56 INFO - Running setup.py install for mozprocess 18:45:56 INFO - Running setup.py install for mozprofile 18:45:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:45:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:45:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:45:57 INFO - Running setup.py install for mozrunner 18:45:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:45:57 INFO - Running setup.py install for mozscreenshot 18:45:57 INFO - Running setup.py install for moztest 18:45:57 INFO - Running setup.py install for mozversion 18:45:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:45:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:45:57 INFO - Cleaning up... 18:45:57 INFO - Return code: 0 18:45:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45: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')]} 18:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45: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')]} 18:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:57 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:45:57 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 18:45:57 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 18:45:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:45:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:45:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:45:57 INFO - 'HOME': '/Users/cltbld', 18:45:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:45:57 INFO - 'LOGNAME': 'cltbld', 18:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:57 INFO - 'MOZ_NO_REMOTE': '1', 18:45:57 INFO - 'NO_EM_RESTART': '1', 18:45:57 INFO - 'PAGER': '/bin/cat', 18:45:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:57 INFO - 'PWD': '/builds/slave/test', 18:45:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:45:57 INFO - 'SHELL': '/bin/bash', 18:45:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:45:57 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:45:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:45:57 INFO - 'USER': 'cltbld', 18:45:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:45:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:57 INFO - 'XPC_FLAGS': '0x0', 18:45:57 INFO - 'XPC_SERVICE_NAME': '0', 18:45:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-7xruZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-vEiGCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-8YCKgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pgOiim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-UFrxxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-q2kkai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-QuajtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-XwXTMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:45:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:45:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hRpps_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:45:58 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)) 18:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-W6pRZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:45:59 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)) 18:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gFparv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:45:59 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)) 18:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-crryVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:45:59 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)) 18:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-_JRG0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:45:59 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)) 18:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-sxhsA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:45:59 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)) 18:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-o_6Ym9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:59 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)) 18:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-cyW9tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:45:59 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)) 18:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:45:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-yiJD55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:45:59 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)) 18:45:59 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)) 18:45:59 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)) 18:45:59 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)) 18:45:59 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)) 18:45:59 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)) 18:45:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:01 INFO - Downloading blessings-1.6.tar.gz 18:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:46:01 INFO - Installing collected packages: blessings 18:46:01 INFO - Running setup.py install for blessings 18:46:01 INFO - Successfully installed blessings 18:46:01 INFO - Cleaning up... 18:46:01 INFO - Return code: 0 18:46:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:46:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:01 INFO - Reading from file tmpfile_stdout 18:46:01 INFO - Current package versions: 18:46:01 INFO - blessings == 1.6 18:46:01 INFO - blobuploader == 1.2.4 18:46:01 INFO - docopt == 0.6.1 18:46:01 INFO - functools32 == 3.2.3-2 18:46:01 INFO - jsonschema == 2.5.1 18:46:01 INFO - manifestparser == 1.1 18:46:01 INFO - mozInstall == 1.12 18:46:01 INFO - mozcrash == 1.0 18:46:01 INFO - mozdebug == 0.1 18:46:01 INFO - mozdevice == 0.51 18:46:01 INFO - mozfile == 1.2 18:46:01 INFO - mozhttpd == 0.7 18:46:01 INFO - mozinfo == 0.10 18:46:01 INFO - mozleak == 0.1 18:46:01 INFO - mozlog == 3.4 18:46:01 INFO - moznetwork == 0.27 18:46:01 INFO - mozprocess == 0.25 18:46:01 INFO - mozprofile == 0.28 18:46:01 INFO - mozrunner == 6.13 18:46:01 INFO - mozscreenshot == 0.1 18:46:01 INFO - mozsystemmonitor == 0.3 18:46:01 INFO - moztest == 0.8 18:46:01 INFO - mozversion == 1.4 18:46:01 INFO - psutil == 3.1.1 18:46:01 INFO - requests == 1.2.3 18:46:01 INFO - wsgiref == 0.1.2 18:46:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:01 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 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:46:01 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 18:46:01 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 18:46:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:46:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:01 INFO - 'HOME': '/Users/cltbld', 18:46:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:01 INFO - 'LOGNAME': 'cltbld', 18:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:01 INFO - 'MOZ_NO_REMOTE': '1', 18:46:01 INFO - 'NO_EM_RESTART': '1', 18:46:01 INFO - 'PAGER': '/bin/cat', 18:46:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:01 INFO - 'PWD': '/builds/slave/test', 18:46:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:01 INFO - 'SHELL': '/bin/bash', 18:46:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:46:01 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:46:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:01 INFO - 'USER': 'cltbld', 18:46:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:01 INFO - 'XPC_FLAGS': '0x0', 18:46:01 INFO - 'XPC_SERVICE_NAME': '0', 18:46:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:02 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)) 18:46:02 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)) 18:46:02 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)) 18:46:02 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)) 18:46:02 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)) 18:46:02 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)) 18:46:02 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)) 18:46:02 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)) 18:46:02 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)) 18:46:02 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:03 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:46:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:46:03 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)) 18:46:03 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)) 18:46:03 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)) 18:46:03 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)) 18:46:03 INFO - Installing collected packages: marionette-driver 18:46:03 INFO - Running setup.py install for marionette-driver 18:46:03 INFO - Successfully installed marionette-driver 18:46:03 INFO - Cleaning up... 18:46:03 INFO - Return code: 0 18:46:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a49c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd04cff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, '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.YpQImPSbVC/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.OB9fqE6Edp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 18:46: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'] in /builds/slave/test/build 18:46: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 18:46:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:46:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:03 INFO - 'HOME': '/Users/cltbld', 18:46:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:03 INFO - 'LOGNAME': 'cltbld', 18:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:03 INFO - 'MOZ_NO_REMOTE': '1', 18:46:03 INFO - 'NO_EM_RESTART': '1', 18:46:03 INFO - 'PAGER': '/bin/cat', 18:46:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:03 INFO - 'PWD': '/builds/slave/test', 18:46:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:03 INFO - 'SHELL': '/bin/bash', 18:46:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:46:03 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:46:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:03 INFO - 'USER': 'cltbld', 18:46:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:03 INFO - 'XPC_FLAGS': '0x0', 18:46:03 INFO - 'XPC_SERVICE_NAME': '0', 18:46:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:46:04 INFO - Cleaning up... 18:46:04 INFO - Return code: 0 18:46:04 INFO - Running post-action listener: _resource_record_post_action 18:46:04 INFO - Running post-action listener: _start_resource_monitoring 18:46:04 INFO - Starting resource monitoring. 18:46:04 INFO - [mozharness: 2017-06-07 01:46:04.142168Z] Finished create-virtualenv step (success) 18:46:04 INFO - [mozharness: 2017-06-07 01:46:04.143189Z] Running install step. 18:46:04 INFO - Running pre-action listener: _resource_record_pre_action 18:46:04 INFO - Running main action method: install 18:46:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:04 INFO - Reading from file tmpfile_stdout 18:46:04 INFO - Detecting whether we're running mozinstall >=1.0... 18:46:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:46:04 INFO - Reading from file tmpfile_stdout 18:46:04 INFO - Output received: 18:46:04 INFO - Usage: mozinstall [options] installer 18:46:04 INFO - Options: 18:46:04 INFO - -h, --help show this help message and exit 18:46:04 INFO - -d DEST, --destination=DEST 18:46:04 INFO - Directory to install application into. [default: 18:46:04 INFO - "/builds/slave/test"] 18:46:04 INFO - --app=APP Application being installed. [default: firefox] 18:46:04 INFO - mkdir: /builds/slave/test/build/application 18:46:04 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'] 18:46:04 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 18:46:25 INFO - Reading from file tmpfile_stdout 18:46:25 INFO - Output received: 18:46:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:46:25 INFO - Running post-action listener: _resource_record_post_action 18:46:25 INFO - [mozharness: 2017-06-07 01:46:25.903154Z] Finished install step (success) 18:46:25 INFO - [mozharness: 2017-06-07 01:46:25.903310Z] Running setup-mitmproxy step. 18:46:25 INFO - Running pre-action listener: _resource_record_pre_action 18:46:25 INFO - Running main action method: setup_mitmproxy 18:46:25 INFO - Skipping: mitmproxy is not required 18:46:25 INFO - Running post-action listener: _resource_record_post_action 18:46:25 INFO - [mozharness: 2017-06-07 01:46:25.903938Z] Finished setup-mitmproxy step (success) 18:46:25 INFO - [mozharness: 2017-06-07 01:46:25.904075Z] Running run-tests step. 18:46:25 INFO - Running pre-action listener: _resource_record_pre_action 18:46:25 INFO - Running main action method: run_tests 18:46:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:46:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:46:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:46:25 INFO - 2017-06-06 18:46:25.918 screenresolution[1625:7470] starting screenresolution argv=screenresolution get 18:46:25 INFO - 2017-06-06 18:46:25.921 screenresolution[1625:7470] Display 0: 1600x1200x32@60 18:46:25 INFO - 2017-06-06 18:46:25.927 screenresolution[1626:7473] starting screenresolution argv=screenresolution list 18:46:25 INFO - Available Modes on Display 0 18:46:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:46:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:46:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:46:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:46:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:46:26 INFO - Intel Iris: 18:46:26 INFO - Chipset Model: Intel Iris 18:46:26 INFO - Type: GPU 18:46:26 INFO - Bus: Built-In 18:46:26 INFO - VRAM (Dynamic, Max): 1536 MB 18:46:26 INFO - Vendor: Intel (0x8086) 18:46:26 INFO - Device ID: 0x0a2e 18:46:26 INFO - Revision ID: 0x0009 18:46:26 INFO - Displays: 18:46:26 INFO - Display: 18:46:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:46:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:46:26 INFO - Display Serial Number: 1600x1200 60 18:46:26 INFO - Main Display: Yes 18:46:26 INFO - Mirror: Off 18:46:26 INFO - Online: Yes 18:46:26 INFO - Rotation: Supported 18:46:26 INFO - Return code: 0 18:46:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:46:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:46:26 INFO - Python 2.7.3 18:46:26 INFO - Return code: 0 18:46:26 INFO - grabbing minidump binary from tooltool 18:46:26 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')]} 18:46:26 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 0x10379fd30>, '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 0x103769cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103790be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:46:26 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 18:46:26 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 18:46:26 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 18:46:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:46:26 INFO - Return code: 0 18:46:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:46:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:46:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:46:26 INFO - ENV: RUST_BACKTRACE is now 1 18:46:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:46:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:46:26 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/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 18:46:26 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/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 18:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpQImPSbVC/Render', 18:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:26 INFO - 'HOME': '/Users/cltbld', 18:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:26 INFO - 'LOGNAME': 'cltbld', 18:46:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:46:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:26 INFO - 'MOZ_NO_REMOTE': '1', 18:46:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:46:26 INFO - 'NO_EM_RESTART': '1', 18:46:26 INFO - 'PAGER': '/bin/cat', 18:46:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:26 INFO - 'PWD': '/builds/slave/test', 18:46:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:26 INFO - 'RUST_BACKTRACE': '1', 18:46:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:46:26 INFO - 'SHELL': '/bin/bash', 18:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OB9fqE6Edp/Listeners', 18:46:26 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 18:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:26 INFO - 'USER': 'cltbld', 18:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:26 INFO - 'XPC_FLAGS': '0x0', 18:46:26 INFO - 'XPC_SERVICE_NAME': '0', 18:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:26 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/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 18:46:26 INFO - mozversion application_buildid: 20170606172244 18:46:26 INFO - mozversion application_changeset: 5801aa478de12a62b2b2982659e787fcc4268d67 18:46:26 INFO - mozversion application_display_name: Nightly 18:46:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:46:26 INFO - mozversion application_name: Firefox 18:46:26 INFO - mozversion application_remotingname: firefox 18:46:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:46:26 INFO - mozversion application_vendor: Mozilla 18:46:26 INFO - mozversion application_version: 55.0a1 18:46:26 INFO - mozversion platform_buildid: 20170606172244 18:46:26 INFO - mozversion platform_changeset: 5801aa478de12a62b2b2982659e787fcc4268d67 18:46:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:46:26 INFO - mozversion platform_version: 55.0a1 18:46:26 INFO - using testdate: 1496799986 18:46:26 INFO - actual date: 1496799986 18:46:26 INFO - starting webserver on 'localhost:49226' 18:46:26 INFO - SUITE-START | Running 2 tests 18:46:26 INFO - TEST-START | dromaeo_css 18:46:26 INFO - operating with platform_type : mac_ 18:46:26 INFO - Initialising browser for dromaeo_css test... 18:46:26 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpXrGD_D/profile 18:46:26 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 18:47:00 INFO - TEST-INFO | 1636: killed by SIGTERM 18:47:00 INFO - Browser initialized. 18:47:00 INFO - Running cycle 1/1 for dromaeo_css test... 18:47:00 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpXrGD_D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:02 INFO - PID 1641 | 2017-06-06 18:47:02.787 plugin-container[1642:7947] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b43, name = 'com.apple.tsm.portname' 18:47:02 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:02 INFO - PID 1641 | 2017-06-06 18:47:02.788 plugin-container[1642:7947] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.CFPasteboardClient' 18:47:02 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:02 INFO - PID 1641 | 2017-06-06 18:47:02.788 plugin-container[1642:7947] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:03 INFO - PID 1641 | 2017-06-06 18:47:03.500 plugin-container[1643:7992] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 18:47:03 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:03 INFO - PID 1641 | 2017-06-06 18:47:03.501 plugin-container[1643:7992] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 18:47:03 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:03 INFO - PID 1641 | 2017-06-06 18:47:03.501 plugin-container[1643:7992] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:12 INFO - PID 1641 | RSS: Main: 252522496 18:47:12 INFO - PID 1641 | 18:50:15 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 18:50:16 INFO - PID 1641 | RSS: Main: 266379264 18:50:16 INFO - PID 1641 | 18:53:18 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 18:53:19 INFO - PID 1641 | RSS: Main: 279150592 18:53:19 INFO - PID 1641 | 18:56:21 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 18:56:21 INFO - PID 1641 | RSS: Main: 278061056 18:56:21 INFO - PID 1641 | 18:59:23 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 18:59:24 INFO - PID 1641 | RSS: Main: 280846336 18:59:24 INFO - PID 1641 | 19:02:27 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 19:02:27 INFO - PID 1641 | RSS: Main: 280084480 19:02:27 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 19:05:29 INFO - PID 1641 | RSS: Main: 279949312 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | __start_tp_report 19:05:29 INFO - PID 1641 | _x_x_mozilla_page_load 19:05:29 INFO - PID 1641 | _x_x_mozilla_page_load_details 19:05:29 INFO - PID 1641 | |i|pagename|runs| 19:05:29 INFO - PID 1641 | |0;dojo.html;3575.5351804265442;3606.5852426970905;3629.147150419651;3649.434337677659;3657.8536858525654;5016.322796422482;6232.813015609528;6260.405261500162;6287.811365659026;6335.5565110442285;6217.129601855744;6267.968660156705;6286.3399343069;6292;6311.77908773193;5407.891842163154;5487.478689524509;5491.643043202199;5495;5505.752241149158;5517.144842549391;5582.944170558303;5601.943980560203;5619.859503512424;5624.015797235488;5373.704446255454;5528.143137813645;5555.9721451531195;5581.609287349887;5612.186232996233;5430.8913821723345;5458.1266997280245;5495.093309603911;5530.806421775219;5565.94247093055;5450.291462109901;5460;5473.343198816167;5484.7531861066345;5487.5884308677005;5306.70813105279;6326.4938804895955;6329.4619957303685;6329.588576742497;6455.548111632188;5052.393712754493;6338.556301058928;6378.330275321071;6650.135482387307;6725.831854203635;6849.1096157499205;6859.348361905611;6882.552634078769;6885.345892140239;6920.619365934872;6266.091416744546;6396.84007899797;6396.904046439307;6403.135576697086;6403.839904002437;6718.294579069225;6783.3555812197765;6820.9658951705915;6915.135608048994;7008.859822803515;5482.862928426742;6391;6492;6567.080608714688;6688.531802773759;6636.402723754885;6675.198976122896;6774.966125169342;6790.456763458911;6795.490338224554;6600.108985287021;6713.1272934519175;6728.360805723449;6758.93241067583;6776.491763117688;6447.097406363113;6721.93278067213;6730.562513436611;6735.393814556714;6748.392644662004;5319.042572336939;5334.093204155225;5387.191921211771;5394.002109609656;5429.647072940246;4834.371531700926;4838.322634831126;4866.6106711463;4899.338589290472;4901.387326584177;6246.281677607133;6320.665438063419;6351.523635727246;6363;6379.457746091542;6619.371159739817;6669.233038200669;6701.798946031627;6751.797446076625;6786.8642627148165;6356.555041147168;6385.297617262097;6387.968060159762;6390.488760899117;6403.263624683128;5607.719614019283;5974.671393073336;6001.7599296027665;6003.129546215861;6015.4586087252355;6720.529562930548;6793.6942837573415;6834.350736680008;6919.861602768017;6950.443964482829;6848.760293389907;6867.931320686729;6880.65596720152;6895.6552172390175;6907.378335949788;5805.8548536286935;6871.931280687129;6942.750304944956;6953.26990665993;6991.056207412138;5671.432856714296;6497.31778163305;6584.078229048033;6596.439302659328;6775.762848300285;5620.18507316445;6759;6843.520953533205;6846.315368463114;6850.349216824394;5991.970040149946;6302.338254483213;6644.33556644351;6654.4676425884845;6665.433438157811;6407.10300557913;6504.284528701744;6505.934940650533;6508.121403610452;6554.63949482783;5417.6478528895495;6395.104685343963;6471.935280647133;6486.708098135501;6505.707243173973;6641.169853768279;6646;6646.9002964956535;6654.800355989328;6685.331466853276;6277.843053923689;6343.429091381798;6475.3200913903365;6494.155759751202;6510.609363438209 19:05:29 INFO - PID 1641 | |1;ext.html;698.8078278473422;823.5182418285303;826.5784449930535;1232.6671798614375;1286.1961274203622;36532.63467365326;36632.45051324232;36677.26645467089;36745.08137296569;36853.07867303315;37018;37314.62685373145;37337.439938400945;37344.43983340252;37345.81327093368;7631.198724133956;7778.611069446533;7881.60591970402;8036.477628954129;8044.919550804505;7997.800054998614;8102.1492743262;8103.311218546431;8109.189081091873;8131.471454355477;8051.194880511931;8070.919290807075;8110.229528194812;8129.715459958903;8146.185381461836;8025.95987020067;8081.353491720648;8097.9595102024705;8101.149379315205;8117.512949223065;7954.164812694642;7971.8405631887035;7995.200479952016;8016.8396632067615;8029.197080291982;34157.97526074222;34268;34405.6559434405;34508.30983380343;34548.65451345479;3524.2246705724956;3544.85820567177;3558.5907620623443;3559.985404159802;3568.6252943440822;28825.855870720512;28969.84120635172;29006.854965725248;29041.854790726124;29086.563701544495;2972.390659914712;2995.35599846032;3018.44158830617;3035.833029183394;3055.4041961817456;175332;177649;263615;281116;281655;46314.5368546317;46607.601771946895;46624.83437914011;46676.29985550219;46679.29981050287;47434;47469.28796068062;47509.287360688926;53271.73364133232;54071.72964135154;14109.647258818406;14162.291885405895;14329.64175895611;14334.068285561614;14385.6403589909;11466.770664586662;11539.884601154048;11545.480453379618;11555.2489088209;11560.710982225344;13731.038827282195;13961.65095872591;13969.301534923214;13985.3706583204;14065.296735163405;5114.232865070195;6094.11635312886;6099.237595300588;6120.490598854448;6148.385161483816;988.4514094677379;1008.3949630221955;1013.8631284776608;1054.520193312041;1059.6662051453768;23218.53562928732;23219;23610.88194559016;24038.63942040871;24358.63462048071;899.7335028483462;1051.7370657335666;1088.4992903264588;1097.2703152403722;1134.8581427321585;11790.351530665725;11809.069867796485;12068.396580170956;12351.135420520477;12614.684632884067;316556;316805;316958.83041169756;317093;318574;50907.98184036298;51251.48748512542;51426;51665;51687.74156129271;1508.0951429142647;1510.932008059618;1542.5063979526444;1545.2428310127957;1552.7049860526463;22101.557968840854;22401.551968960204;22889.427764306027;23856.04575816948;24094.879525602963;36599.81700091589;36618.90143295706;36643.26713465769;36644.63355366412;36751.26497469982;195653;236772;237308;237395;239046;34331.970040898814;34405.655943440244;34817.65182348144;34838.82580587081;34957.126071848405;41059.58940410558;41446;42493;42871.71384858459;43136;28380.290492737848;28925.842966281114;28951.276218093873;29472.852635736683;30031.699683003764;25132;28330;28771.712282876902;29581.2604684876;30135.246618834706;29461.26346841346;35234.647653523134;35706;35732.82133589315;35854.924352269474;26790.866045669645;30183.69816301809;30313.545296821005;30992.845035774677;31180.37639247157;23677.289681309587;23972.640410394208;24226;24348.269551913472;24416.877915611018 19:05:29 INFO - PID 1641 | |2;jquery.html;379.1998882358222;417.14485305124487;429.93590862615014;436.92572262715333;446.40627964806805;14490.20303883286;15039.398424063025;21961.02350788429;22455.775442245595;22777.658335124946;22155.335339939786;22928;23063.308100756964;23080.538389232246;23128.65307020396;5026.472220416858;5988.820335389945;6014.5565064384455;6109.266887973449;6177.722002509875;6026.945284575204;6094.634321940675;6112.69436528174;6121.112438696387;6133.264008319008;5770.798022069229;5991.700414979256;6034.0948857671265;6040.75836966523;6069.453749162574;6038.0942858571125;6067.362926892657;6103.084537319415;6107.541934354941;6119;5421.457854214586;5423;5423.755930983115;5431.701256430894;5458.317710286215;27873.58189627157;27889.721102789117;28168.577471337947;28334.858325708447;28346.72439740196;2172.261431113429;2277.3623385451933;2296.207808306132;2317.7218733752056;2318.4667526469007;19127.713084303745;19280.807191928183;19455.416337509898;19469.415917522496;19553;2304.101400453824;2322.071171531384;2371.359732872132;2376.346504711201;2382.9642555361684;210552.63065792777;211595;211823.8817611835;214828;215409;20156.798432015785;22263;25280.367990800376;25418.872905635722;25691.48617027649;43339.566604334184;43675;43744.12511749811;43755.34366984498;43833.78083109564;10941.4529273536;11087.33475991443;11149.721256968478;11157.94421027906;11241.83137252942;8758.386912916034;8834.86747698785;8836.204741573289;8868.556572171366;8883.33374996865;7765.922340776633;11886.049116070693;11972;12030.939845300718;12096.81854772179;2281.0989659084566;2288.9084436622684;2290.358699564125;2292.1862738727846;2466.9876650616634;927.4435338796668;941.1153515695224;944.4097439100562;989.946016538971;993.3394292795355;20389.286374976804;20715.860627665323;20952.790472095083;21343.46641333948;21400.143994240367;962.4581836203693;965.2567523007385;995.9950200248952;1007.8437842134481;1009.369144284822;8787.297016238686;9449.669261575811;10644.361338319726;13494;14109.78835317471;563701;579239;581771;584774;587122;160858.1957090207;167366;167662;168605.31394686142;168914;1190.6309044196328;1226.9447874845653;1257.5724253753767;1257.7610254051701;1262.6780171056505;16727.916360418607;22584.548309034057;22922.54154916926;23213.41966450852;24330.661813599712;72185;72254.63872680604;75379.62310188414;75676.62161689156;76974;106173.87652246757;106667.4666626662;106812;106877.39683904903;107112;16082.276297566403;16487.83512164911;16742.665146696756;16774.0774257417;16775.496735097968;12886.67783305425;16993.575160621083;18406.539836504195;18586;18629.254829806658;14602.488912887999;14974.026688265229;15020.09879407239;15040.774388384401;15104.169270690218;13101.41043653057;13157.60527184186;13176.934115329363;13467.3939672713;13611.31943402806;14941.178235197169;15095.622609434851;15308.464203752816;15325.846741532889;15557.922210388875;15964.361425542847;16091.517254482384;16257.187140643156;16323.836761632232;16331.346746130497;14728.852711473177;14777.113373197213;14794.852051479778;14860.62848428798;14907.55277341638 19:05:29 INFO - PID 1641 | |3;mootools.html;1402.8456869744327;1454.7017861338427;1464.9267536623172;1469.1992863889186;1479.1494890437998;10086;11489.080873530118;11549.595764148256;12033.939830300847;12034;11886.919789036905;11894;12056.156069075168;12061.638150855488;12134.635960921187;2553.080890879282;2570.7814835738986;2596.610508423733;2600.739926007403;2602.4795040991785;2440.3533063738123;2450.816188785848;2460.9753902460925;2471.7033955925313;2472.987635061822;2514.09492582671;2527.0776166699115;2537.3656585853537;2538.9492210155786;2539.606361014046;2486.254123762873;2494.0023990403893;2503.236512863576;2514.9688627662754;2515.622656601506;2474.9628755568683;2499.737527559598;2500.5124000819665;2522.382016405992;2585.5863061910004;11646;11726.589569365031;11731.589394371156;11741.823872641917;11742.471588778526;6502.187226596676;6653.833654158636;6747.865042699119;6755.189377274709;6757.121574195323;4058.492688413948;4181.310083836163;4253.51084625269;4281.507626622947;4305.612494875445;1221.7477085986845;1223.0704664454988;1240.1195151442487;1255.1151438236113;1256.5413624027165;15339.233038348038;15590.298436570494;16517.752233716503;16650.50048498547;16657;5111.923321150186;5649.152627105967;5841.561882858802;5875.618084824473;5909.349971503131;12477.438515266835;12484;12498.625041248777;12502.74994500105;12514.18657787241;6408.807735767841;6525.0538671893255;6599.043138744855;6617.503687223478;6621.966890165519;6003.909941350883;6184.783532576428;6220.657863817443;6235.625862448268;6242.438180563771;4446.888827779331;4528.298113792367;4589.265717485253;4596.10375976688;4614.077184563033;1275.8213850061;1299.3698056442618;1302.6222395505388;1317.5783749200164;1322.1207896748554;819.2872201185007;829.3323874281141;855.9529225892511;859.8409294280453;889.9777505562413;6300.936990630035;6438.581492202992;6500.804975850732;6683.832904177434;6727.327267273234;599.5054080383701;601.0263373335138;605.4429924469499;611.3275397063284;616.4205646692185;3487.843047062888;4122.9381559276635;4176.77027763476;4303.526612072669;4352.912941741148;14466.91498137644;14855.554333370017;15000.399984000736;15224.771628425582;15305.311260993281;12433.93783031079;14044.876409887185;14930.25348732559;15247.16140612277;15285.923570382078;8286.751397458085;8483.236508714246;8591.44155629882;8606.698765543177;8613.397062205586;6349;6924.23682607521;7110.32451917067;7307.0135531702535;7534.698612055676;13639.727205455636;13652.65868353299;13697.383617737425;13884.444622214998;13943.930280348533;13980.930095349866;14028;14152.221627810157;14291.213983230606;14459.855401445851;8609.794628752104;8787.912120878966;8862.113788621085;8925.330600205141;8943.418677785923;10685.198610104118;10816.837747433638;10823.783524329627;10825.566977321134;10872.695276566863;9005.14451127142;9205.401648892801;9223.538823058687;9254.907450925404;9407.85888211691;8983.281337492854;9053.59258833341;9110.31672624569;9181.678641247525;9279.582418791306;8176.509409435215;8263.132371100948;8300.501969881827;8318.79203019905;8379.45533540318;5423.213634023124;6236.719347629277;6356.141920840812;6367.968160159169;6496.155499785186;6321.715522801577;6389.8722025560155;6496.285408604956;6513.674316284072;6575.342465753386 19:05:29 INFO - PID 1641 | |4;prototype.html;339.8012162884712;350.1788306421441;351.8223297234897;389.28954657749614;405.1653593597188;5381.623286369956;6490.318516555759;6543.934560654392;6546.738130474775;6584.572002819819;5204.739763011853;5351.464853514646;6305.558610897239;6331.765305765378;6372.012338087604;3415.975207437771;4165.729227600212;4329.913401731964;4374.971881607819;4546.181687296285;4024.9597504025023;4445.444319460067;4646.442426908776;4667.393238878958;4682.204025315705;3806.9911473459556;4230.9153816923645;4236.957630423687;4615.953840461586;4630.777226931075;4622.537746225385;4633.536646335357;4645.46577143626;4646.697964632322;4698.577128058491;3615.258871931248;3755.3615885299564;3991.6627929643314;4027.2952233359338;4027.9597204027878;6345.159903627942;6360.236771587439;6365.395287447732;6370.522210834206;6407.391297826699;5609.186667933127;5613.691246981414;5615.354234263071;5617.775288988436;5626.7186640668215;5743.942560574383;5746;6925.272846351161;6944.6180460074675;6970.268121847234;4566.817327306937;4593.736722401366;4596.1267359201975;4605.815767369335;4611.861644150647;5477.534409575231;5596.636218645775;7079.44252264501;7197.7044132056735;7321.8631473217465;4520.864374068783;4567.903703111229;4609.055143695535;4609.700369475973;4614.638681588939;4300.37644541546;4664.673472856867;4687.976560117246;4697.530246975275;4717.669763116617;4797.616190704805;5404.297441332602;5487.560995120461;5488.341399032061;5506.614536982373;6405.775797847053;6629.436497897636;6896.620685862325;6918.8616227676175;6928.826779330456;1548.914809685478;1604.9518514444585;1610.2673283655909;1616.3776945875923;1622.3997121065045;907.6732376344511;960.2989817433173;997.8602995580625;1012.6506355307407;1027.445179603021;5389.9191512127345;5416.9729151353995;5497.972510137503;5711;6460.483161347081;7120.394766444911;7166.355028047396;7168.641567921583;7173.400471566171;7175.533590316612;6407.03894415832;6411.711472983727;6423.775167869102;6428.325025872309;6441;3266.70599646033;3730.24678401151;3747.0819649186124;3747.512823332949;3758.3235017697075;7269.345758881726;7284.453665975073;7286.963565182246;7306.780796576111;7332.340089391981;6750.594964302158;7335.816604584821;7356.705731770669;7370.226126256666;7371.410287177013;7233.493655444174;7313.707451701979;7314.670839812221;7329.633518323956;7332.230115837867;6279.340669229848;6550.541462097607;7098.3256590623805;7152.713891444284;7155.352519567793;6101.969490152521;6815.488838337244;7057.929420705727;7079.575225486487;7126.619402351192;6730.226024007106;6968.616726079911;7253.310935461122;7266.382357499671;7275.417966562556;4626;4630.930536042029;4636.258198688193;4647.651426142985;4655.511171326962;6368.808935731936;7148.392386646985;7157.964210178916;7171.748988785576;7183.425325973805;6070.271567411763;6889.724411023503;6897.620630865351;6924.272951340036;6949.270326615632;5940.405959404025;6236.1313795652695;6613.6693165340575;6875.243723190545;6899.517033807585;5837.795677151279;6323.557350985387;6627.304133065959;6758.898616520854;6796.76211332601;5552.028395030934;6833.760818371333;6910.343517365842;6923.376896079377;6938.306169383021;5921.437463440966;6434.903476447764;6718.596884186964;6763.830904227434;6792.252852186354 19:05:29 INFO - PID 1641 | |5;yui.html;711.2887112887113;798.8002999250187;845.2941793602341;866.3459088388266;879.5206612396244;14666.19335936523;15238.771418428732;16731.996080235178;17157.828421715778;17200.967941923478;17104.144792760373;17719.291228350852;17853.464396068106;17973.550661233476;18020.54948626285;971.5822196455504;1007.5717819926539;1033.3438266700662;1034.2450011491615;1038.2368958815264;1029.521272608237;1030.8866024737272;1032.8554002439664;1034.2553361579687;1035.5288343803552;947.5830634520789;1071.1912506057915;1076.4079756134095;1077.106002018323;1078.7896360209763;907.3331101640289;1036.445501656614;1057.0116940660507;1063.526730604881;1065.2862582070031;684.288340126268;770.2605498721234;778.1518145221679;785.4737325991542;791.3787676673841;16815.15924203797;17254.223559939706;17257.482275531755;17422.651546969115;17462.388816391493;17683.469495915142;17941.372051978247;18062.096895155322;18312.542186445313;18385.63228735431;12727.109102362838;14221.644458888508;14783.778243326251;14814.037087589271;14821.629459263497;3580.964190358089;3586.4799604057266;3611.963880361215;3613.837377318027;3624.9850041988284;126755.3662231683;142607;144464;145303;148750;10486.37081775096;13137;13267.469301228035;13360.799588006188;13369.665758355924;26238.212853614423;26518.867405663234;26676.19971400861;26842.463150736876;26860.328491787863;6434.613923164625;7652.617369131521;7696.615169241515;7734;7837.921620783719;3409.829508524564;3432.622411534729;3457.17027913299;3474.8088855112705;3486.1981744198974;3900.902477438087;4413.602775750133;4441.045175287329;4467.195904737114;4501.909961800746;849.3035710717153;861.2119910282147;866.1035827918074;873.5763154869883;876.9386142970059;638.9041643753474;652.3997921911853;655.4821690864164;673.7877568565887;678.2335960364757;10464;10692.930706929401;11044.558217671203;12182.208156469802;12460.439280232411;628.6605233174034;641.7529251238213;663.4526515624533;674.4772801079203;676.1953275602116;5084.2627818966785;5418.539424148913;5508.063629183049;5535.944640553624;5593.664380137205;149987.25006375115;151793.72309415368;152105.23947380192;156552;157620;118424;119727;121098;127039.3648031754;129616.05575916369;100431.49784251032;102530;103375;106263.4686826561;106297;9723.270754693567;10548.736281593021;10725.946370268099;11897.702557435787;12162.746794619583;37827.810860945516;39478.296833148794;43710.781446092566;43991.78004109959;45496.77251613721;65456;80752.19247807447;83081.16918830734;84641.57679211565;88512.22975540324;14829.25853707332;17170.656586867943;17535.21091550858;17892.55268618243;17919.46241612754;18219;18556.628867422845;18827.811721882605;18996.71504927455;19421.51446213856;17429.651406972043;17759.934403935804;17793.199306031;18040.729389058914;18124.72812907834;17508.94946303226;17640;17665;17703;17788;16438;17050.06224657656;17384.391546295818;17798.02110883862;17837.821621783616;11590.652280431601;11982.98144657679;11989.400529973293;12010.156749839967;12369.505219791108;12724.936375318064;12817.743645127232;13194.01044921654;13371.866281337063;13417.396217170055 19:05:29 INFO - PID 1641 | __end_tp_report 19:05:29 INFO - PID 1641 | __start_cc_report 19:05:29 INFO - PID 1641 | _x_x_mozilla_cycle_collect,448 19:05:29 INFO - PID 1641 | __end_cc_report 19:05:29 INFO - PID 1641 | __startTimestamp1496801129786__endTimestamp 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | ------- Summary: start ------- 19:05:29 INFO - PID 1641 | Number of tests: 6 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6173.62 Median:6395.10 stddev:725.28 (11.3%) stddev-sans-first:698.28 19:05:29 INFO - PID 1641 | Values: 3575.5 3606.6 3629.1 3649.4 3657.9 5016.3 6232.8 6260.4 6287.8 6335.6 6217.1 6268.0 6286.3 6292.0 6311.8 5407.9 5487.5 5491.6 5495.0 5505.8 5517.1 5582.9 5601.9 5619.9 5624.0 5373.7 5528.1 5556.0 5581.6 5612.2 5430.9 5458.1 5495.1 5530.8 5565.9 5450.3 5460.0 5473.3 5484.8 5487.6 5306.7 6326.5 6329.5 6329.6 6455.5 5052.4 6338.6 6378.3 6650.1 6725.8 6849.1 6859.3 6882.6 6885.3 6920.6 6266.1 6396.8 6396.9 6403.1 6403.8 6718.3 6783.4 6821.0 6915.1 7008.9 5482.9 6391.0 6492.0 6567.1 6688.5 6636.4 6675.2 6775.0 6790.5 6795.5 6600.1 6713.1 6728.4 6758.9 6776.5 6447.1 6721.9 6730.6 6735.4 6748.4 5319.0 5334.1 5387.2 5394.0 5429.6 4834.4 4838.3 4866.6 4899.3 4901.4 6246.3 6320.7 6351.5 6363.0 6379.5 6619.4 6669.2 6701.8 6751.8 6786.9 6356.6 6385.3 6388.0 6390.5 6403.3 5607.7 5974.7 6001.8 6003.1 6015.5 6720.5 6793.7 6834.4 6919.9 6950.4 6848.8 6867.9 6880.7 6895.7 6907.4 5805.9 6871.9 6942.8 6953.3 6991.1 5671.4 6497.3 6584.1 6596.4 6775.8 5620.2 6759.0 6843.5 6846.3 6850.3 5992.0 6302.3 6644.3 6654.5 6665.4 6407.1 6504.3 6505.9 6508.1 6554.6 5417.6 6395.1 6471.9 6486.7 6505.7 6641.2 6646.0 6646.9 6654.8 6685.3 6277.8 6343.4 6475.3 6494.2 6510.6 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:41190.78 Median:176365.99 stddev:69571.37 (39.4%) stddev-sans-first:69700.11 19:05:29 INFO - PID 1641 | Values: 698.8 823.5 826.6 1232.7 1286.2 36532.6 36632.5 36677.3 36745.1 36853.1 37018.0 37314.6 37337.4 37344.4 37345.8 7631.2 7778.6 7881.6 8036.5 8044.9 7997.8 8102.1 8103.3 8109.2 8131.5 8051.2 8070.9 8110.2 8129.7 8146.2 8026.0 8081.4 8098.0 8101.1 8117.5 7954.2 7971.8 7995.2 8016.8 8029.2 34158.0 34268.0 34405.7 34508.3 34548.7 3524.2 3544.9 3558.6 3560.0 3568.6 28825.9 28969.8 29006.9 29041.9 29086.6 2972.4 2995.4 3018.4 3035.8 3055.4 175332.0 177649.0 263615.0 281116.0 281655.0 46314.5 46607.6 46624.8 46676.3 46679.3 47434.0 47469.3 47509.3 53271.7 54071.7 14109.6 14162.3 14329.6 14334.1 14385.6 11466.8 11539.9 11545.5 11555.2 11560.7 13731.0 13961.7 13969.3 13985.4 14065.3 5114.2 6094.1 6099.2 6120.5 6148.4 988.5 1008.4 1013.9 1054.5 1059.7 23218.5 23219.0 23610.9 24038.6 24358.6 899.7 1051.7 1088.5 1097.3 1134.9 11790.4 11809.1 12068.4 12351.1 12614.7 316556.0 316805.0 316958.8 317093.0 318574.0 50908.0 51251.5 51426.0 51665.0 51687.7 1508.1 1510.9 1542.5 1545.2 1552.7 22101.6 22401.6 22889.4 23856.0 24094.9 36599.8 36618.9 36643.3 36644.6 36751.3 195653.0 236772.0 237308.0 237395.0 239046.0 34332.0 34405.7 34817.7 34838.8 34957.1 41059.6 41446.0 42493.0 42871.7 43136.0 28380.3 28925.8 28951.3 29472.9 30031.7 25132.0 28330.0 28771.7 29581.3 30135.2 29461.3 35234.6 35706.0 35732.8 35854.9 26790.9 30183.7 30313.5 30992.8 31180.4 23677.3 23972.6 24226.0 24348.3 24416.9 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:42520.23 Median:12529.38 stddev:101450.37 (809.7%) stddev-sans-first:101685.63 19:05:29 INFO - PID 1641 | Values: 379.2 417.1 429.9 436.9 446.4 14490.2 15039.4 21961.0 22455.8 22777.7 22155.3 22928.0 23063.3 23080.5 23128.7 5026.5 5988.8 6014.6 6109.3 6177.7 6026.9 6094.6 6112.7 6121.1 6133.3 5770.8 5991.7 6034.1 6040.8 6069.5 6038.1 6067.4 6103.1 6107.5 6119.0 5421.5 5423.0 5423.8 5431.7 5458.3 27873.6 27889.7 28168.6 28334.9 28346.7 2172.3 2277.4 2296.2 2317.7 2318.5 19127.7 19280.8 19455.4 19469.4 19553.0 2304.1 2322.1 2371.4 2376.3 2383.0 210552.6 211595.0 211823.9 214828.0 215409.0 20156.8 22263.0 25280.4 25418.9 25691.5 43339.6 43675.0 43744.1 43755.3 43833.8 10941.5 11087.3 11149.7 11157.9 11241.8 8758.4 8834.9 8836.2 8868.6 8883.3 7765.9 11886.0 11972.0 12030.9 12096.8 2281.1 2288.9 2290.4 2292.2 2467.0 927.4 941.1 944.4 989.9 993.3 20389.3 20715.9 20952.8 21343.5 21400.1 962.5 965.3 996.0 1007.8 1009.4 8787.3 9449.7 10644.4 13494.0 14109.8 563701.0 579239.0 581771.0 584774.0 587122.0 160858.2 167366.0 167662.0 168605.3 168914.0 1190.6 1226.9 1257.6 1257.8 1262.7 16727.9 22584.5 22922.5 23213.4 24330.7 72185.0 72254.6 75379.6 75676.6 76974.0 106173.9 106667.5 106812.0 106877.4 107112.0 16082.3 16487.8 16742.7 16774.1 16775.5 12886.7 16993.6 18406.5 18586.0 18629.3 14602.5 14974.0 15020.1 15040.8 15104.2 13101.4 13157.6 13176.9 13467.4 13611.3 14941.2 15095.6 15308.5 15325.8 15557.9 15964.4 16091.5 16257.2 16323.8 16331.3 14728.9 14777.1 14794.9 14860.6 14907.6 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:7169.52 Median:3773.17 stddev:4518.59 (119.8%) stddev-sans-first:4510.48 19:05:29 INFO - PID 1641 | Values: 1402.8 1454.7 1464.9 1469.2 1479.1 10086.0 11489.1 11549.6 12033.9 12034.0 11886.9 11894.0 12056.2 12061.6 12134.6 2553.1 2570.8 2596.6 2600.7 2602.5 2440.4 2450.8 2461.0 2471.7 2473.0 2514.1 2527.1 2537.4 2538.9 2539.6 2486.3 2494.0 2503.2 2515.0 2515.6 2475.0 2499.7 2500.5 2522.4 2585.6 11646.0 11726.6 11731.6 11741.8 11742.5 6502.2 6653.8 6747.9 6755.2 6757.1 4058.5 4181.3 4253.5 4281.5 4305.6 1221.7 1223.1 1240.1 1255.1 1256.5 15339.2 15590.3 16517.8 16650.5 16657.0 5111.9 5649.2 5841.6 5875.6 5909.3 12477.4 12484.0 12498.6 12502.7 12514.2 6408.8 6525.1 6599.0 6617.5 6622.0 6003.9 6184.8 6220.7 6235.6 6242.4 4446.9 4528.3 4589.3 4596.1 4614.1 1275.8 1299.4 1302.6 1317.6 1322.1 819.3 829.3 856.0 859.8 890.0 6300.9 6438.6 6500.8 6683.8 6727.3 599.5 601.0 605.4 611.3 616.4 3487.8 4122.9 4176.8 4303.5 4352.9 14466.9 14855.6 15000.4 15224.8 15305.3 12433.9 14044.9 14930.3 15247.2 15285.9 8286.8 8483.2 8591.4 8606.7 8613.4 6349.0 6924.2 7110.3 7307.0 7534.7 13639.7 13652.7 13697.4 13884.4 13943.9 13980.9 14028.0 14152.2 14291.2 14459.9 8609.8 8787.9 8862.1 8925.3 8943.4 10685.2 10816.8 10823.8 10825.6 10872.7 9005.1 9205.4 9223.5 9254.9 9407.9 8983.3 9053.6 9110.3 9181.7 9279.6 8176.5 8263.1 8300.5 8318.8 8379.5 5423.2 6236.7 6356.1 6368.0 6496.2 6321.7 6389.9 6496.3 6513.7 6575.3 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5489.47 Median:6292.45 stddev:1770.45 (28.1%) stddev-sans-first:1732.71 19:05:29 INFO - PID 1641 | Values: 339.8 350.2 351.8 389.3 405.2 5381.6 6490.3 6543.9 6546.7 6584.6 5204.7 5351.5 6305.6 6331.8 6372.0 3416.0 4165.7 4329.9 4375.0 4546.2 4025.0 4445.4 4646.4 4667.4 4682.2 3807.0 4230.9 4237.0 4616.0 4630.8 4622.5 4633.5 4645.5 4646.7 4698.6 3615.3 3755.4 3991.7 4027.3 4028.0 6345.2 6360.2 6365.4 6370.5 6407.4 5609.2 5613.7 5615.4 5617.8 5626.7 5743.9 5746.0 6925.3 6944.6 6970.3 4566.8 4593.7 4596.1 4605.8 4611.9 5477.5 5596.6 7079.4 7197.7 7321.9 4520.9 4567.9 4609.1 4609.7 4614.6 4300.4 4664.7 4688.0 4697.5 4717.7 4797.6 5404.3 5487.6 5488.3 5506.6 6405.8 6629.4 6896.6 6918.9 6928.8 1548.9 1605.0 1610.3 1616.4 1622.4 907.7 960.3 997.9 1012.7 1027.4 5389.9 5417.0 5498.0 5711.0 6460.5 7120.4 7166.4 7168.6 7173.4 7175.5 6407.0 6411.7 6423.8 6428.3 6441.0 3266.7 3730.2 3747.1 3747.5 3758.3 7269.3 7284.5 7287.0 7306.8 7332.3 6750.6 7335.8 7356.7 7370.2 7371.4 7233.5 7313.7 7314.7 7329.6 7332.2 6279.3 6550.5 7098.3 7152.7 7155.4 6102.0 6815.5 7057.9 7079.6 7126.6 6730.2 6968.6 7253.3 7266.4 7275.4 4626.0 4630.9 4636.3 4647.7 4655.5 6368.8 7148.4 7158.0 7171.7 7183.4 6070.3 6889.7 6897.6 6924.3 6949.3 5940.4 6236.1 6613.7 6875.2 6899.5 5837.8 6323.6 6627.3 6758.9 6796.8 5552.0 6833.8 6910.3 6923.4 6938.3 5921.4 6434.9 6718.6 6763.8 6792.3 19:05:29 INFO - PID 1641 | 19:05:29 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:25998.49 Median:17773.97 stddev:40328.22 (226.9%) stddev-sans-first:40396.65 19:05:29 INFO - PID 1641 | Values: 711.3 798.8 845.3 866.3 879.5 14666.2 15238.8 16732.0 17157.8 17201.0 17104.1 17719.3 17853.5 17973.6 18020.5 971.6 1007.6 1033.3 1034.2 1038.2 1029.5 1030.9 1032.9 1034.3 1035.5 947.6 1071.2 1076.4 1077.1 1078.8 907.3 1036.4 1057.0 1063.5 1065.3 684.3 770.3 778.2 785.5 791.4 16815.2 17254.2 17257.5 17422.7 17462.4 17683.5 17941.4 18062.1 18312.5 18385.6 12727.1 14221.6 14783.8 14814.0 14821.6 3581.0 3586.5 3612.0 3613.8 3625.0 126755.4 142607.0 144464.0 145303.0 148750.0 10486.4 13137.0 13267.5 13360.8 13369.7 26238.2 26518.9 26676.2 26842.5 26860.3 6434.6 7652.6 7696.6 7734.0 7837.9 3409.8 3432.6 3457.2 3474.8 3486.2 3900.9 4413.6 4441.0 4467.2 4501.9 849.3 861.2 866.1 873.6 876.9 638.9 652.4 655.5 673.8 678.2 10464.0 10692.9 11044.6 12182.2 12460.4 628.7 641.8 663.5 674.5 676.2 5084.3 5418.5 5508.1 5535.9 5593.7 149987.3 151793.7 152105.2 156552.0 157620.0 118424.0 119727.0 121098.0 127039.4 129616.1 100431.5 102530.0 103375.0 106263.5 106297.0 9723.3 10548.7 10725.9 11897.7 12162.7 37827.8 39478.3 43710.8 43991.8 45496.8 65456.0 80752.2 83081.2 84641.6 88512.2 14829.3 17170.7 17535.2 17892.6 17919.5 18219.0 18556.6 18827.8 18996.7 19421.5 17429.7 17759.9 17793.2 18040.7 18124.7 17508.9 17640.0 17665.0 17703.0 17788.0 16438.0 17050.1 17384.4 17798.0 17837.8 11590.7 11983.0 11989.4 12010.2 12369.5 12724.9 12817.7 13194.0 13371.9 13417.4 19:05:29 INFO - PID 1641 | -------- Summary: end -------- 19:05:29 INFO - PID 1641 | 19:05:30 INFO - TEST-INFO | 1641: exit 0 19:05:30 INFO - TEST-OK | dromaeo_css | took 1143945ms 19:05:30 INFO - TEST-START | kraken 19:05:30 INFO - operating with platform_type : mac_ 19:05:30 INFO - Initialising browser for kraken test... 19:05:30 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpOZTEr3/profile 19:05:30 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 19:06:04 INFO - TEST-INFO | 1653: killed by SIGTERM 19:06:04 INFO - Browser initialized. 19:06:04 INFO - Running cycle 1/1 for kraken test... 19:06:04 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpOZTEr3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:06 INFO - PID 1657 | 2017-06-06 19:06:06.540 plugin-container[1658:10633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 19:06:06 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:06 INFO - PID 1657 | 2017-06-06 19:06:06.541 plugin-container[1658:10633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x982f, name = 'com.apple.CFPasteboardClient' 19:06:06 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:06 INFO - PID 1657 | 2017-06-06 19:06:06.541 plugin-container[1658:10633] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:06:07 INFO - PID 1657 | 2017-06-06 19:06:07.025 plugin-container[1659:10682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 19:06:07 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:07 INFO - PID 1657 | 2017-06-06 19:06:07.026 plugin-container[1659:10682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 19:06:07 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:07 INFO - PID 1657 | 2017-06-06 19:06:07.026 plugin-container[1659:10682] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:06:16 INFO - PID 1657 | RSS: Main: 252805120 19:06:16 INFO - PID 1657 | 19:06:19 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 19:06:20 INFO - PID 1657 | RSS: Main: 265691136 19:06:20 INFO - PID 1657 | 19:06:22 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 19:06:23 INFO - PID 1657 | RSS: Main: 274432000 19:06:23 INFO - PID 1657 | 19:06:26 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 19:06:27 INFO - PID 1657 | RSS: Main: 277454848 19:06:27 INFO - PID 1657 | 19:06:29 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 19:06:29 INFO - PID 1657 | RSS: Main: 279711744 19:06:29 INFO - PID 1657 | 19:06:31 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:06:32 INFO - PID 1657 | RSS: Main: 279961600 19:06:32 INFO - PID 1657 | 19:06:35 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 19:06:36 INFO - PID 1657 | RSS: Main: 286224384 19:06:36 INFO - PID 1657 | 19:06:39 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 19:06:39 INFO - PID 1657 | RSS: Main: 288124928 19:06:39 INFO - PID 1657 | 19:06:42 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 19:06:43 INFO - PID 1657 | RSS: Main: 287182848 19:06:43 INFO - PID 1657 | 19:06:45 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:06:45 INFO - PID 1657 | RSS: Main: 286674944 19:06:45 INFO - PID 1657 | 19:06:47 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:06:48 INFO - PID 1657 | RSS: Main: 286568448 19:06:48 INFO - PID 1657 | 19:06:50 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:06:51 INFO - PID 1657 | RSS: Main: 286482432 19:06:51 INFO - PID 1657 | 19:06:54 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:06:54 INFO - PID 1657 | RSS: Main: 286220288 19:06:54 INFO - PID 1657 | 19:06:57 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:06:58 INFO - PID 1657 | RSS: Main: 286302208 19:06:58 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 19:07:00 INFO - PID 1657 | RSS: Main: 288165888 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | __start_tp_report 19:07:00 INFO - PID 1657 | _x_x_mozilla_page_load 19:07:00 INFO - PID 1657 | _x_x_mozilla_page_load_details 19:07:00 INFO - PID 1657 | |i|pagename|runs| 19:07:00 INFO - PID 1657 | |0;ai-astar;103;115;114;112;114;114;110;115;114;113 19:07:00 INFO - PID 1657 | |1;audio-beat-detection;147;115;120;185;143;143;110;115;141;113 19:07:00 INFO - PID 1657 | |2;audio-dft;195;206;201;211;215;211;224;201;201;202 19:07:00 INFO - PID 1657 | |3;audio-fft;76;56;56;85;56;59;78;87;76;58 19:07:00 INFO - PID 1657 | |4;audio-oscillator;79;99;115;87;70;69;96;90;92;89 19:07:00 INFO - PID 1657 | |5;imaging-gaussian-blur;88;89;89;89;89;88;95;89;133;90 19:07:00 INFO - PID 1657 | |6;imaging-darkroom;148;96;96;96;104;98;97;96;144;101 19:07:00 INFO - PID 1657 | |7;imaging-desaturate;124;141;91;92;91;90;95;89;99;107 19:07:00 INFO - PID 1657 | |8;json-parse-financial;51;51;50;51;53;52;54;52;52;75 19:07:00 INFO - PID 1657 | |9;json-stringify-tinderbox;61;59;60;68;68;63;57;55;58;69 19:07:00 INFO - PID 1657 | |10;stanford-crypto-aes;84;84;85;83;96;75;108;114;122;88 19:07:00 INFO - PID 1657 | |11;stanford-crypto-ccm;135;136;141;128;156;142;148;146;143;147 19:07:00 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;180;180;181;181;184;182;181;184;182;181 19:07:00 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;67;64;64;69;65;65;66;63;68;89 19:07:00 INFO - PID 1657 | __end_tp_report 19:07:00 INFO - PID 1657 | __start_cc_report 19:07:00 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1204 19:07:00 INFO - PID 1657 | __end_cc_report 19:07:00 INFO - PID 1657 | __startTimestamp1496801220267__endTimestamp 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | ------- Summary: start ------- 19:07:00 INFO - PID 1657 | Number of tests: 14 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:112.40 Median:114.00 stddev:3.63 (3.2%) stddev-sans-first:1.59 19:07:00 INFO - PID 1657 | Values: 103.0 115.0 114.0 112.0 114.0 114.0 110.0 115.0 114.0 113.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:142.00 stddev:23.36 (16.4%) stddev-sans-first:24.23 19:07:00 INFO - PID 1657 | Values: 147.0 115.0 120.0 185.0 143.0 143.0 110.0 115.0 141.0 113.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:206.70 Median:208.50 stddev:8.58 (4.1%) stddev-sans-first:7.98 19:07:00 INFO - PID 1657 | Values: 195.0 206.0 201.0 211.0 215.0 211.0 224.0 201.0 201.0 202.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:68.70 Median:76.00 stddev:12.85 (16.9%) stddev-sans-first:13.36 19:07:00 INFO - PID 1657 | Values: 76.0 56.0 56.0 85.0 56.0 59.0 78.0 87.0 76.0 58.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:89.50 stddev:13.74 (15.3%) stddev-sans-first:14.12 19:07:00 INFO - PID 1657 | Values: 79.0 99.0 115.0 87.0 70.0 69.0 96.0 90.0 92.0 89.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:93.90 Median:89.00 stddev:13.88 (15.6%) stddev-sans-first:14.56 19:07:00 INFO - PID 1657 | Values: 88.0 89.0 89.0 89.0 89.0 88.0 95.0 89.0 133.0 90.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:96.00 stddev:20.43 (21.3%) stddev-sans-first:15.58 19:07:00 INFO - PID 1657 | Values: 148.0 96.0 96.0 96.0 104.0 98.0 97.0 96.0 144.0 101.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:91.00 stddev:17.46 (19.2%) stddev-sans-first:16.58 19:07:00 INFO - PID 1657 | Values: 124.0 141.0 91.0 92.0 91.0 90.0 95.0 89.0 99.0 107.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:52.00 stddev:7.43 (14.3%) stddev-sans-first:7.80 19:07:00 INFO - PID 1657 | Values: 51.0 51.0 50.0 51.0 53.0 52.0 54.0 52.0 52.0 75.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:5.01 (8.1%) stddev-sans-first:5.30 19:07:00 INFO - PID 1657 | Values: 61.0 59.0 60.0 68.0 68.0 63.0 57.0 55.0 58.0 69.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:84.00 stddev:15.57 (18.5%) stddev-sans-first:16.10 19:07:00 INFO - PID 1657 | Values: 84.0 84.0 85.0 83.0 96.0 75.0 108.0 114.0 122.0 88.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:144.50 stddev:7.86 (5.4%) stddev-sans-first:7.89 19:07:00 INFO - PID 1657 | Values: 135.0 136.0 141.0 128.0 156.0 142.0 148.0 146.0 143.0 147.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:181.50 stddev:1.43 (0.8%) stddev-sans-first:1.39 19:07:00 INFO - PID 1657 | Values: 180.0 180.0 181.0 181.0 184.0 182.0 181.0 184.0 182.0 181.0 19:07:00 INFO - PID 1657 | 19:07:00 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:66.50 stddev:7.62 (11.5%) stddev-sans-first:8.07 19:07:00 INFO - PID 1657 | Values: 67.0 64.0 64.0 69.0 65.0 65.0 66.0 63.0 68.0 89.0 19:07:00 INFO - PID 1657 | -------- Summary: end -------- 19:07:00 INFO - PID 1657 | 19:07:01 INFO - TEST-INFO | 1657: exit 0 19:07:01 INFO - TEST-OK | kraken | took 90603ms 19:07:01 INFO - SUITE-END | took 1234s 19:07:01 INFO - Completed test suite (00:20:34) 19:07:01 INFO - javascript benchmark 19:07:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8168.68015179671, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3575.5351804265442, 3606.5852426970905, 3629.147150419651, 3649.434337677659, 3657.8536858525654, 5016.322796422482, 6232.813015609528, 6260.405261500162, 6287.811365659026, 6335.5565110442285, 6217.129601855744, 6267.968660156705, 6286.3399343069, 6292.0, 6311.77908773193, 5407.891842163154, 5487.478689524509, 5491.643043202199, 5495.0, 5505.752241149158, 5517.144842549391, 5582.944170558303, 5601.943980560203, 5619.859503512424, 5624.015797235488, 5373.704446255454, 5528.143137813645, 5555.9721451531195, 5581.609287349887, 5612.186232996233, 5430.8913821723345, 5458.1266997280245, 5495.093309603911, 5530.806421775219, 5565.94247093055, 5450.291462109901, 5460.0, 5473.343198816167, 5484.7531861066345, 5487.5884308677005, 5306.70813105279, 6326.4938804895955, 6329.4619957303685, 6329.588576742497, 6455.548111632188, 5052.393712754493, 6338.556301058928, 6378.330275321071, 6650.135482387307, 6725.831854203635, 6849.1096157499205, 6859.348361905611, 6882.552634078769, 6885.345892140239, 6920.619365934872, 6266.091416744546, 6396.84007899797, 6396.904046439307, 6403.135576697086, 6403.839904002437, 6718.294579069225, 6783.3555812197765, 6820.9658951705915, 6915.135608048994, 7008.859822803515, 5482.862928426742, 6391.0, 6492.0, 6567.080608714688, 6688.531802773759, 6636.402723754885, 6675.198976122896, 6774.966125169342, 6790.456763458911, 6795.490338224554, 6600.108985287021, 6713.1272934519175, 6728.360805723449, 6758.93241067583, 6776.491763117688, 6447.097406363113, 6721.93278067213, 6730.562513436611, 6735.393814556714, 6748.392644662004, 5319.042572336939, 5334.093204155225, 5387.191921211771, 5394.002109609656, 5429.647072940246, 4834.371531700926, 4838.322634831126, 4866.6106711463, 4899.338589290472, 4901.387326584177, 6246.281677607133, 6320.665438063419, 6351.523635727246, 6363.0, 6379.457746091542, 6619.371159739817, 6669.233038200669, 6701.798946031627, 6751.797446076625, 6786.8642627148165, 6356.555041147168, 6385.297617262097, 6387.968060159762, 6390.488760899117, 6403.263624683128, 5607.719614019283, 5974.671393073336, 6001.7599296027665, 6003.129546215861, 6015.4586087252355, 6720.529562930548, 6793.6942837573415, 6834.350736680008, 6919.861602768017, 6950.443964482829, 6848.760293389907, 6867.931320686729, 6880.65596720152, 6895.6552172390175, 6907.378335949788, 5805.8548536286935, 6871.931280687129, 6942.750304944956, 6953.26990665993, 6991.056207412138, 5671.432856714296, 6497.31778163305, 6584.078229048033, 6596.439302659328, 6775.762848300285, 5620.18507316445, 6759.0, 6843.520953533205, 6846.315368463114, 6850.349216824394, 5991.970040149946, 6302.338254483213, 6644.33556644351, 6654.4676425884845, 6665.433438157811, 6407.10300557913, 6504.284528701744, 6505.934940650533, 6508.121403610452, 6554.63949482783, 5417.6478528895495, 6395.104685343963, 6471.935280647133, 6486.708098135501, 6505.707243173973, 6641.169853768279, 6646.0, 6646.9002964956535, 6654.800355989328, 6685.331466853276, 6277.843053923689, 6343.429091381798, 6475.3200913903365, 6494.155759751202, 6510.609363438209], "value": 6129.044116540914, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.8078278473422, 823.5182418285303, 826.5784449930535, 1232.6671798614375, 1286.1961274203622, 36532.63467365326, 36632.45051324232, 36677.26645467089, 36745.08137296569, 36853.07867303315, 37018.0, 37314.62685373145, 37337.439938400945, 37344.43983340252, 37345.81327093368, 7631.198724133956, 7778.611069446533, 7881.60591970402, 8036.477628954129, 8044.919550804505, 7997.800054998614, 8102.1492743262, 8103.311218546431, 8109.189081091873, 8131.471454355477, 8051.194880511931, 8070.919290807075, 8110.229528194812, 8129.715459958903, 8146.185381461836, 8025.95987020067, 8081.353491720648, 8097.9595102024705, 8101.149379315205, 8117.512949223065, 7954.164812694642, 7971.8405631887035, 7995.200479952016, 8016.8396632067615, 8029.197080291982, 34157.97526074222, 34268.0, 34405.6559434405, 34508.30983380343, 34548.65451345479, 3524.2246705724956, 3544.85820567177, 3558.5907620623443, 3559.985404159802, 3568.6252943440822, 28825.855870720512, 28969.84120635172, 29006.854965725248, 29041.854790726124, 29086.563701544495, 2972.390659914712, 2995.35599846032, 3018.44158830617, 3035.833029183394, 3055.4041961817456, 175332.0, 177649.0, 263615.0, 281116.0, 281655.0, 46314.5368546317, 46607.601771946895, 46624.83437914011, 46676.29985550219, 46679.29981050287, 47434.0, 47469.28796068062, 47509.287360688926, 53271.73364133232, 54071.72964135154, 14109.647258818406, 14162.291885405895, 14329.64175895611, 14334.068285561614, 14385.6403589909, 11466.770664586662, 11539.884601154048, 11545.480453379618, 11555.2489088209, 11560.710982225344, 13731.038827282195, 13961.65095872591, 13969.301534923214, 13985.3706583204, 14065.296735163405, 5114.232865070195, 6094.11635312886, 6099.237595300588, 6120.490598854448, 6148.385161483816, 988.4514094677379, 1008.3949630221955, 1013.8631284776608, 1054.520193312041, 1059.6662051453768, 23218.53562928732, 23219.0, 23610.88194559016, 24038.63942040871, 24358.63462048071, 899.7335028483462, 1051.7370657335666, 1088.4992903264588, 1097.2703152403722, 1134.8581427321585, 11790.351530665725, 11809.069867796485, 12068.396580170956, 12351.135420520477, 12614.684632884067, 316556.0, 316805.0, 316958.83041169756, 317093.0, 318574.0, 50907.98184036298, 51251.48748512542, 51426.0, 51665.0, 51687.74156129271, 1508.0951429142647, 1510.932008059618, 1542.5063979526444, 1545.2428310127957, 1552.7049860526463, 22101.557968840854, 22401.551968960204, 22889.427764306027, 23856.04575816948, 24094.879525602963, 36599.81700091589, 36618.90143295706, 36643.26713465769, 36644.63355366412, 36751.26497469982, 195653.0, 236772.0, 237308.0, 237395.0, 239046.0, 34331.970040898814, 34405.655943440244, 34817.65182348144, 34838.82580587081, 34957.126071848405, 41059.58940410558, 41446.0, 42493.0, 42871.71384858459, 43136.0, 28380.290492737848, 28925.842966281114, 28951.276218093873, 29472.852635736683, 30031.699683003764, 25132.0, 28330.0, 28771.712282876902, 29581.2604684876, 30135.246618834706, 29461.26346841346, 35234.647653523134, 35706.0, 35732.82133589315, 35854.924352269474, 26790.866045669645, 30183.69816301809, 30313.545296821005, 30992.845035774677, 31180.37639247157, 23677.289681309587, 23972.640410394208, 24226.0, 24348.269551913472, 24416.877915611018], "value": 16899.77422302463, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.1998882358222, 417.14485305124487, 429.93590862615014, 436.92572262715333, 446.40627964806805, 14490.20303883286, 15039.398424063025, 21961.02350788429, 22455.775442245595, 22777.658335124946, 22155.335339939786, 22928.0, 23063.308100756964, 23080.538389232246, 23128.65307020396, 5026.472220416858, 5988.820335389945, 6014.5565064384455, 6109.266887973449, 6177.722002509875, 6026.945284575204, 6094.634321940675, 6112.69436528174, 6121.112438696387, 6133.264008319008, 5770.798022069229, 5991.700414979256, 6034.0948857671265, 6040.75836966523, 6069.453749162574, 6038.0942858571125, 6067.362926892657, 6103.084537319415, 6107.541934354941, 6119.0, 5421.457854214586, 5423.0, 5423.755930983115, 5431.701256430894, 5458.317710286215, 27873.58189627157, 27889.721102789117, 28168.577471337947, 28334.858325708447, 28346.72439740196, 2172.261431113429, 2277.3623385451933, 2296.207808306132, 2317.7218733752056, 2318.4667526469007, 19127.713084303745, 19280.807191928183, 19455.416337509898, 19469.415917522496, 19553.0, 2304.101400453824, 2322.071171531384, 2371.359732872132, 2376.346504711201, 2382.9642555361684, 210552.63065792777, 211595.0, 211823.8817611835, 214828.0, 215409.0, 20156.798432015785, 22263.0, 25280.367990800376, 25418.872905635722, 25691.48617027649, 43339.566604334184, 43675.0, 43744.12511749811, 43755.34366984498, 43833.78083109564, 10941.4529273536, 11087.33475991443, 11149.721256968478, 11157.94421027906, 11241.83137252942, 8758.386912916034, 8834.86747698785, 8836.204741573289, 8868.556572171366, 8883.33374996865, 7765.922340776633, 11886.049116070693, 11972.0, 12030.939845300718, 12096.81854772179, 2281.0989659084566, 2288.9084436622684, 2290.358699564125, 2292.1862738727846, 2466.9876650616634, 927.4435338796668, 941.1153515695224, 944.4097439100562, 989.946016538971, 993.3394292795355, 20389.286374976804, 20715.860627665323, 20952.790472095083, 21343.46641333948, 21400.143994240367, 962.4581836203693, 965.2567523007385, 995.9950200248952, 1007.8437842134481, 1009.369144284822, 8787.297016238686, 9449.669261575811, 10644.361338319726, 13494.0, 14109.78835317471, 563701.0, 579239.0, 581771.0, 584774.0, 587122.0, 160858.1957090207, 167366.0, 167662.0, 168605.31394686142, 168914.0, 1190.6309044196328, 1226.9447874845653, 1257.5724253753767, 1257.7610254051701, 1262.6780171056505, 16727.916360418607, 22584.548309034057, 22922.54154916926, 23213.41966450852, 24330.661813599712, 72185.0, 72254.63872680604, 75379.62310188414, 75676.62161689156, 76974.0, 106173.87652246757, 106667.4666626662, 106812.0, 106877.39683904903, 107112.0, 16082.276297566403, 16487.83512164911, 16742.665146696756, 16774.0774257417, 16775.496735097968, 12886.67783305425, 16993.575160621083, 18406.539836504195, 18586.0, 18629.254829806658, 14602.488912887999, 14974.026688265229, 15020.09879407239, 15040.774388384401, 15104.169270690218, 13101.41043653057, 13157.60527184186, 13176.934115329363, 13467.3939672713, 13611.31943402806, 14941.178235197169, 15095.622609434851, 15308.464203752816, 15325.846741532889, 15557.922210388875, 15964.361425542847, 16091.517254482384, 16257.187140643156, 16323.836761632232, 16331.346746130497, 14728.852711473177, 14777.113373197213, 14794.852051479778, 14860.62848428798, 14907.55277341638], "value": 12368.053968960407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.8456869744327, 1454.7017861338427, 1464.9267536623172, 1469.1992863889186, 1479.1494890437998, 10086.0, 11489.080873530118, 11549.595764148256, 12033.939830300847, 12034.0, 11886.919789036905, 11894.0, 12056.156069075168, 12061.638150855488, 12134.635960921187, 2553.080890879282, 2570.7814835738986, 2596.610508423733, 2600.739926007403, 2602.4795040991785, 2440.3533063738123, 2450.816188785848, 2460.9753902460925, 2471.7033955925313, 2472.987635061822, 2514.09492582671, 2527.0776166699115, 2537.3656585853537, 2538.9492210155786, 2539.606361014046, 2486.254123762873, 2494.0023990403893, 2503.236512863576, 2514.9688627662754, 2515.622656601506, 2474.9628755568683, 2499.737527559598, 2500.5124000819665, 2522.382016405992, 2585.5863061910004, 11646.0, 11726.589569365031, 11731.589394371156, 11741.823872641917, 11742.471588778526, 6502.187226596676, 6653.833654158636, 6747.865042699119, 6755.189377274709, 6757.121574195323, 4058.492688413948, 4181.310083836163, 4253.51084625269, 4281.507626622947, 4305.612494875445, 1221.7477085986845, 1223.0704664454988, 1240.1195151442487, 1255.1151438236113, 1256.5413624027165, 15339.233038348038, 15590.298436570494, 16517.752233716503, 16650.50048498547, 16657.0, 5111.923321150186, 5649.152627105967, 5841.561882858802, 5875.618084824473, 5909.349971503131, 12477.438515266835, 12484.0, 12498.625041248777, 12502.74994500105, 12514.18657787241, 6408.807735767841, 6525.0538671893255, 6599.043138744855, 6617.503687223478, 6621.966890165519, 6003.909941350883, 6184.783532576428, 6220.657863817443, 6235.625862448268, 6242.438180563771, 4446.888827779331, 4528.298113792367, 4589.265717485253, 4596.10375976688, 4614.077184563033, 1275.8213850061, 1299.3698056442618, 1302.6222395505388, 1317.5783749200164, 1322.1207896748554, 819.2872201185007, 829.3323874281141, 855.9529225892511, 859.8409294280453, 889.9777505562413, 6300.936990630035, 6438.581492202992, 6500.804975850732, 6683.832904177434, 6727.327267273234, 599.5054080383701, 601.0263373335138, 605.4429924469499, 611.3275397063284, 616.4205646692185, 3487.843047062888, 4122.9381559276635, 4176.77027763476, 4303.526612072669, 4352.912941741148, 14466.91498137644, 14855.554333370017, 15000.399984000736, 15224.771628425582, 15305.311260993281, 12433.93783031079, 14044.876409887185, 14930.25348732559, 15247.16140612277, 15285.923570382078, 8286.751397458085, 8483.236508714246, 8591.44155629882, 8606.698765543177, 8613.397062205586, 6349.0, 6924.23682607521, 7110.32451917067, 7307.0135531702535, 7534.698612055676, 13639.727205455636, 13652.65868353299, 13697.383617737425, 13884.444622214998, 13943.930280348533, 13980.930095349866, 14028.0, 14152.221627810157, 14291.213983230606, 14459.855401445851, 8609.794628752104, 8787.912120878966, 8862.113788621085, 8925.330600205141, 8943.418677785923, 10685.198610104118, 10816.837747433638, 10823.783524329627, 10825.566977321134, 10872.695276566863, 9005.14451127142, 9205.401648892801, 9223.538823058687, 9254.907450925404, 9407.85888211691, 8983.281337492854, 9053.59258833341, 9110.31672624569, 9181.678641247525, 9279.582418791306, 8176.509409435215, 8263.132371100948, 8300.501969881827, 8318.79203019905, 8379.45533540318, 5423.213634023124, 6236.719347629277, 6356.141920840812, 6367.968160159169, 6496.155499785186, 6321.715522801577, 6389.8722025560155, 6496.285408604956, 6513.674316284072, 6575.342465753386], "value": 5385.652280875233, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.8012162884712, 350.1788306421441, 351.8223297234897, 389.28954657749614, 405.1653593597188, 5381.623286369956, 6490.318516555759, 6543.934560654392, 6546.738130474775, 6584.572002819819, 5204.739763011853, 5351.464853514646, 6305.558610897239, 6331.765305765378, 6372.012338087604, 3415.975207437771, 4165.729227600212, 4329.913401731964, 4374.971881607819, 4546.181687296285, 4024.9597504025023, 4445.444319460067, 4646.442426908776, 4667.393238878958, 4682.204025315705, 3806.9911473459556, 4230.9153816923645, 4236.957630423687, 4615.953840461586, 4630.777226931075, 4622.537746225385, 4633.536646335357, 4645.46577143626, 4646.697964632322, 4698.577128058491, 3615.258871931248, 3755.3615885299564, 3991.6627929643314, 4027.2952233359338, 4027.9597204027878, 6345.159903627942, 6360.236771587439, 6365.395287447732, 6370.522210834206, 6407.391297826699, 5609.186667933127, 5613.691246981414, 5615.354234263071, 5617.775288988436, 5626.7186640668215, 5743.942560574383, 5746.0, 6925.272846351161, 6944.6180460074675, 6970.268121847234, 4566.817327306937, 4593.736722401366, 4596.1267359201975, 4605.815767369335, 4611.861644150647, 5477.534409575231, 5596.636218645775, 7079.44252264501, 7197.7044132056735, 7321.8631473217465, 4520.864374068783, 4567.903703111229, 4609.055143695535, 4609.700369475973, 4614.638681588939, 4300.37644541546, 4664.673472856867, 4687.976560117246, 4697.530246975275, 4717.669763116617, 4797.616190704805, 5404.297441332602, 5487.560995120461, 5488.341399032061, 5506.614536982373, 6405.775797847053, 6629.436497897636, 6896.620685862325, 6918.8616227676175, 6928.826779330456, 1548.914809685478, 1604.9518514444585, 1610.2673283655909, 1616.3776945875923, 1622.3997121065045, 907.6732376344511, 960.2989817433173, 997.8602995580625, 1012.6506355307407, 1027.445179603021, 5389.9191512127345, 5416.9729151353995, 5497.972510137503, 5711.0, 6460.483161347081, 7120.394766444911, 7166.355028047396, 7168.641567921583, 7173.400471566171, 7175.533590316612, 6407.03894415832, 6411.711472983727, 6423.775167869102, 6428.325025872309, 6441.0, 3266.70599646033, 3730.24678401151, 3747.0819649186124, 3747.512823332949, 3758.3235017697075, 7269.345758881726, 7284.453665975073, 7286.963565182246, 7306.780796576111, 7332.340089391981, 6750.594964302158, 7335.816604584821, 7356.705731770669, 7370.226126256666, 7371.410287177013, 7233.493655444174, 7313.707451701979, 7314.670839812221, 7329.633518323956, 7332.230115837867, 6279.340669229848, 6550.541462097607, 7098.3256590623805, 7152.713891444284, 7155.352519567793, 6101.969490152521, 6815.488838337244, 7057.929420705727, 7079.575225486487, 7126.619402351192, 6730.226024007106, 6968.616726079911, 7253.310935461122, 7266.382357499671, 7275.417966562556, 4626.0, 4630.930536042029, 4636.258198688193, 4647.651426142985, 4655.511171326962, 6368.808935731936, 7148.392386646985, 7157.964210178916, 7171.748988785576, 7183.425325973805, 6070.271567411763, 6889.724411023503, 6897.620630865351, 6924.272951340036, 6949.270326615632, 5940.405959404025, 6236.1313795652695, 6613.6693165340575, 6875.243723190545, 6899.517033807585, 5837.795677151279, 6323.557350985387, 6627.304133065959, 6758.898616520854, 6796.76211332601, 5552.028395030934, 6833.760818371333, 6910.343517365842, 6923.376896079377, 6938.306169383021, 5921.437463440966, 6434.903476447764, 6718.596884186964, 6763.830904227434, 6792.252852186354], "value": 4920.0933460770275, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.2887112887113, 798.8002999250187, 845.2941793602341, 866.3459088388266, 879.5206612396244, 14666.19335936523, 15238.771418428732, 16731.996080235178, 17157.828421715778, 17200.967941923478, 17104.144792760373, 17719.291228350852, 17853.464396068106, 17973.550661233476, 18020.54948626285, 971.5822196455504, 1007.5717819926539, 1033.3438266700662, 1034.2450011491615, 1038.2368958815264, 1029.521272608237, 1030.8866024737272, 1032.8554002439664, 1034.2553361579687, 1035.5288343803552, 947.5830634520789, 1071.1912506057915, 1076.4079756134095, 1077.106002018323, 1078.7896360209763, 907.3331101640289, 1036.445501656614, 1057.0116940660507, 1063.526730604881, 1065.2862582070031, 684.288340126268, 770.2605498721234, 778.1518145221679, 785.4737325991542, 791.3787676673841, 16815.15924203797, 17254.223559939706, 17257.482275531755, 17422.651546969115, 17462.388816391493, 17683.469495915142, 17941.372051978247, 18062.096895155322, 18312.542186445313, 18385.63228735431, 12727.109102362838, 14221.644458888508, 14783.778243326251, 14814.037087589271, 14821.629459263497, 3580.964190358089, 3586.4799604057266, 3611.963880361215, 3613.837377318027, 3624.9850041988284, 126755.3662231683, 142607.0, 144464.0, 145303.0, 148750.0, 10486.37081775096, 13137.0, 13267.469301228035, 13360.799588006188, 13369.665758355924, 26238.212853614423, 26518.867405663234, 26676.19971400861, 26842.463150736876, 26860.328491787863, 6434.613923164625, 7652.617369131521, 7696.615169241515, 7734.0, 7837.921620783719, 3409.829508524564, 3432.622411534729, 3457.17027913299, 3474.8088855112705, 3486.1981744198974, 3900.902477438087, 4413.602775750133, 4441.045175287329, 4467.195904737114, 4501.909961800746, 849.3035710717153, 861.2119910282147, 866.1035827918074, 873.5763154869883, 876.9386142970059, 638.9041643753474, 652.3997921911853, 655.4821690864164, 673.7877568565887, 678.2335960364757, 10464.0, 10692.930706929401, 11044.558217671203, 12182.208156469802, 12460.439280232411, 628.6605233174034, 641.7529251238213, 663.4526515624533, 674.4772801079203, 676.1953275602116, 5084.2627818966785, 5418.539424148913, 5508.063629183049, 5535.944640553624, 5593.664380137205, 149987.25006375115, 151793.72309415368, 152105.23947380192, 156552.0, 157620.0, 118424.0, 119727.0, 121098.0, 127039.3648031754, 129616.05575916369, 100431.49784251032, 102530.0, 103375.0, 106263.4686826561, 106297.0, 9723.270754693567, 10548.736281593021, 10725.946370268099, 11897.702557435787, 12162.746794619583, 37827.810860945516, 39478.296833148794, 43710.781446092566, 43991.78004109959, 45496.77251613721, 65456.0, 80752.19247807447, 83081.16918830734, 84641.57679211565, 88512.22975540324, 14829.25853707332, 17170.656586867943, 17535.21091550858, 17892.55268618243, 17919.46241612754, 18219.0, 18556.628867422845, 18827.811721882605, 18996.71504927455, 19421.51446213856, 17429.651406972043, 17759.934403935804, 17793.199306031, 18040.729389058914, 18124.72812907834, 17508.94946303226, 17640.0, 17665.0, 17703.0, 17788.0, 16438.0, 17050.06224657656, 17384.391546295818, 17798.02110883862, 17837.821621783616, 11590.652280431601, 11982.98144657679, 11989.400529973293, 12010.156749839967, 12369.505219791108, 12724.936375318064, 12817.743645127232, 13194.01044921654, 13371.866281337063, 13417.396217170055], "value": 8751.721182474563, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 114.0, 112.0, 114.0, 114.0, 110.0, 115.0, 114.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 120.0, 185.0, 143.0, 143.0, 110.0, 115.0, 141.0, 113.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 206.0, 201.0, 211.0, 215.0, 211.0, 224.0, 201.0, 201.0, 202.0], "value": 206.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 56.0, 56.0, 85.0, 56.0, 59.0, 78.0, 87.0, 76.0, 58.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 99.0, 115.0, 87.0, 70.0, 69.0, 96.0, 90.0, 92.0, 89.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 89.0, 89.0, 88.0, 95.0, 89.0, 133.0, 90.0], "value": 93.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 96.0, 96.0, 96.0, 104.0, 98.0, 97.0, 96.0, 144.0, 101.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 141.0, 91.0, 92.0, 91.0, 90.0, 95.0, 89.0, 99.0, 107.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 51.0, 53.0, 52.0, 54.0, 52.0, 52.0, 75.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 60.0, 68.0, 68.0, 63.0, 57.0, 55.0, 58.0, 69.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 83.0, 96.0, 75.0, 108.0, 114.0, 122.0, 88.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 141.0, 128.0, 156.0, 142.0, 148.0, 146.0, 143.0, 147.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 180.0, 181.0, 181.0, 184.0, 182.0, 181.0, 184.0, 182.0, 181.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 69.0, 65.0, 65.0, 66.0, 63.0, 68.0, 89.0], "value": 68.0, "unit": "score"}]}]} 19:07:01 INFO - Return code: 0 19:07:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:07:01 INFO - # TBPL SUCCESS # 19:07:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:07:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:07:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:07:01 INFO - 2017-06-06 19:07:01.656 screenresolution[1665:11066] starting screenresolution argv=screenresolution get 19:07:01 INFO - 2017-06-06 19:07:01.659 screenresolution[1665:11066] Display 0: 1600x1200x32@60 19:07:01 INFO - 2017-06-06 19:07:01.665 screenresolution[1666:11069] starting screenresolution argv=screenresolution list 19:07:01 INFO - Available Modes on Display 0 19:07:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:07:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:07:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:07:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:07:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:07:01 INFO - Intel Iris: 19:07:01 INFO - Chipset Model: Intel Iris 19:07:01 INFO - Type: GPU 19:07:01 INFO - Bus: Built-In 19:07:01 INFO - VRAM (Dynamic, Max): 1536 MB 19:07:01 INFO - Vendor: Intel (0x8086) 19:07:01 INFO - Device ID: 0x0a2e 19:07:01 INFO - Revision ID: 0x0009 19:07:01 INFO - Displays: 19:07:01 INFO - Display: 19:07:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:07:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:07:01 INFO - Display Serial Number: 1600x1200 60 19:07:01 INFO - Main Display: Yes 19:07:01 INFO - Mirror: Off 19:07:01 INFO - Online: Yes 19:07:01 INFO - Rotation: Supported 19:07:01 INFO - Return code: 0 19:07:01 INFO - Running post-action listener: _resource_record_post_action 19:07:01 INFO - [mozharness: 2017-06-07 02:07:01.900733Z] Finished run-tests step (success) 19:07:01 INFO - Running post-run listener: _resource_record_post_run 19:07:01 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 41735680; Write bytes: 568851456; Read time: 468; Write time: 2102 19:07:01 INFO - TinderboxPrint: CPU usage
53.2% 19:07:01 INFO - TinderboxPrint: I/O read bytes / time
41,735,680 / 468 19:07:01 INFO - TinderboxPrint: I/O write bytes / time
568,851,456 / 2,102 19:07:01 INFO - TinderboxPrint: CPU idle
2,354.8 (46.8%) 19:07:01 INFO - TinderboxPrint: CPU system
1,017.9 (20.2%) 19:07:01 INFO - TinderboxPrint: CPU user
1,657.0 (32.9%) 19:07:01 INFO - TinderboxPrint: Swap in / out
285,396,992 / 0 19:07:01 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231778816; Write bytes: 231880704; Read time: 16725; Write time: 514 19:07:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:07:01 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 40699392; Write bytes: 318522368; Read time: 447; Write time: 1543 19:07:02 INFO - Running post-run listener: _upload_blobber_files 19:07:02 INFO - Blob upload gear active. 19:07:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:07:02 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 19:07:02 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'] 19:07:02 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 19:07:02 INFO - (blobuploader) - INFO - Open directory for files ... 19:07:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:07:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:07:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:03 INFO - (blobuploader) - INFO - Done attempting. 19:07:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:07:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:07:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:04 INFO - (blobuploader) - INFO - Done attempting. 19:07:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:07:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:07:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:05 INFO - (blobuploader) - INFO - Done attempting. 19:07:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:07:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:07:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:07 INFO - (blobuploader) - INFO - Done attempting. 19:07:07 INFO - (blobuploader) - INFO - Iteration through files over. 19:07:07 INFO - Return code: 0 19:07:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:07:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:07:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4062ea0bb8069b03b6bd93c3bd287bcfa53e5fffadfe79ff2d3c7663ccc0b52fe62982230d344fcb554ad7ae9f737656979037f0bc125c53f65b9db18f94c0ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72f8b97d52b2b3cbcd89f9c5ef20809a465d61e7019ad955e1afe390f4f20f36063278e8f43fab63ca4f2a2090cd92d28a5e4d1c34b516bd8f307006f10fb514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6506cc10393581435b65ca7abdf721991ef209e16a09969678c588c3e9ecd380be056c1df28fd398b6ab2cc56ff2b0273a3f128a76a27ce369f1022171b79158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/583d917c9fbfbd72c741c5a7cea50df61559895132a76fadc04e40882e76e7412a46b497a4b9bc2aea80f60d68f75bd7333b1f5b112c01fd3eb2099eda2c3a72"} 19:07:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:07:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:07:07 INFO - Contents: 19:07:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4062ea0bb8069b03b6bd93c3bd287bcfa53e5fffadfe79ff2d3c7663ccc0b52fe62982230d344fcb554ad7ae9f737656979037f0bc125c53f65b9db18f94c0ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72f8b97d52b2b3cbcd89f9c5ef20809a465d61e7019ad955e1afe390f4f20f36063278e8f43fab63ca4f2a2090cd92d28a5e4d1c34b516bd8f307006f10fb514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6506cc10393581435b65ca7abdf721991ef209e16a09969678c588c3e9ecd380be056c1df28fd398b6ab2cc56ff2b0273a3f128a76a27ce369f1022171b79158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/583d917c9fbfbd72c741c5a7cea50df61559895132a76fadc04e40882e76e7412a46b497a4b9bc2aea80f60d68f75bd7333b1f5b112c01fd3eb2099eda2c3a72"} 19:07:07 INFO - Running post-run listener: copy_logs_to_upload_dir 19:07:07 INFO - Copying logs to upload dir... 19:07:07 INFO - mkdir: /builds/slave/test/build/upload/logs 19:07:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.551666 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-06 19:07:07.679760) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 19:07:07.683468) ========= 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.YpQImPSbVC/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.OB9fqE6Edp/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/4062ea0bb8069b03b6bd93c3bd287bcfa53e5fffadfe79ff2d3c7663ccc0b52fe62982230d344fcb554ad7ae9f737656979037f0bc125c53f65b9db18f94c0ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72f8b97d52b2b3cbcd89f9c5ef20809a465d61e7019ad955e1afe390f4f20f36063278e8f43fab63ca4f2a2090cd92d28a5e4d1c34b516bd8f307006f10fb514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6506cc10393581435b65ca7abdf721991ef209e16a09969678c588c3e9ecd380be056c1df28fd398b6ab2cc56ff2b0273a3f128a76a27ce369f1022171b79158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/583d917c9fbfbd72c741c5a7cea50df61559895132a76fadc04e40882e76e7412a46b497a4b9bc2aea80f60d68f75bd7333b1f5b112c01fd3eb2099eda2c3a72"} build_url:https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011009 build_url: 'https://queue.taskcluster.net/v1/task/TX0BylbVTBeK15F7jyDbZQ/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/4062ea0bb8069b03b6bd93c3bd287bcfa53e5fffadfe79ff2d3c7663ccc0b52fe62982230d344fcb554ad7ae9f737656979037f0bc125c53f65b9db18f94c0ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72f8b97d52b2b3cbcd89f9c5ef20809a465d61e7019ad955e1afe390f4f20f36063278e8f43fab63ca4f2a2090cd92d28a5e4d1c34b516bd8f307006f10fb514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6506cc10393581435b65ca7abdf721991ef209e16a09969678c588c3e9ecd380be056c1df28fd398b6ab2cc56ff2b0273a3f128a76a27ce369f1022171b79158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/583d917c9fbfbd72c741c5a7cea50df61559895132a76fadc04e40882e76e7412a46b497a4b9bc2aea80f60d68f75bd7333b1f5b112c01fd3eb2099eda2c3a72"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 19:07:07.717202) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 19:07:07.717567) ========= 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.YpQImPSbVC/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.OB9fqE6Edp/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005017 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 19:07:07.766094) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 19:07:07.766423) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 19:07:07.766756) ========= ========= Total master_lag: 0.19 =========