builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0034 starttime: 1495664093.77 results: success (0) revision: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.765392) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.765981) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.766281) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.793387) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.793820) ========= 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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-05-24 15:14:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.34M=0.001s 2017-05-24 15:14:53 (9.34 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.914024) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.914360) ========= 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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.028422 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.965706) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:53.966171) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 --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 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rMmHS2vtyY/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.gWIlgEJTbB/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-05-24 15:14:54,070 truncating revision to first 12 chars 2017-05-24 15:14:54,070 Setting DEBUG logging. 2017-05-24 15:14:54,070 attempt 1/10 2017-05-24 15:14:54,070 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/934ff2cb74b0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 15:14:54,362 unpacking tar archive at: mozilla-central-934ff2cb74b0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.588163 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:54.578181) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:54.578585) ========= script_repo_revision: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:54.578986) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:54.579342) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 15:14:54.599312) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-05-24 15:14:54.599776) ========= /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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:14:54 INFO - MultiFileLogger online at 20170524 15:14:54 in /builds/slave/test 15:14:54 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 15:14:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:14:54 INFO - {'append_to_log': False, 15:14:54 INFO - 'base_work_dir': '/builds/slave/test', 15:14:54 INFO - 'blob_upload_branch': 'Firefox', 15:14:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:14:54 INFO - 'branch': 'Firefox', 15:14:54 INFO - 'buildbot_json_path': 'buildprops.json', 15:14:54 INFO - 'config_files': ('talos/mac_config.py',), 15:14:54 INFO - 'default_actions': ('clobber', 15:14:54 INFO - 'read-buildbot-config', 15:14:54 INFO - 'download-and-extract', 15:14:54 INFO - 'populate-webroot', 15:14:54 INFO - 'create-virtualenv', 15:14:54 INFO - 'install', 15:14:54 INFO - 'run-tests'), 15:14:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:14:54 INFO - 'download_minidump_stackwalk': True, 15:14:54 INFO - 'download_symbols': 'ondemand', 15:14:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:14:54 INFO - 'tooltool.py': '/tools/tooltool.py', 15:14:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:14:54 INFO - '/tools/misc-python/virtualenv.py')}, 15:14:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:14:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:14:54 INFO - 'gecko_profile': False, 15:14:54 INFO - 'gecko_profile_interval': 0, 15:14:54 INFO - 'installer_path': 'installer.exe', 15:14:54 INFO - 'log_level': 'info', 15:14:54 INFO - 'log_name': 'talos', 15:14:54 INFO - 'log_to_console': True, 15:14:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:14:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:14:54 INFO - 'opt_config_files': (), 15:14:54 INFO - 'pip_index': False, 15:14:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:14:54 INFO - 'cmd': ('bash', 15:14:54 INFO - '-c', 15:14:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:14:54 INFO - 'enabled': True, 15:14:54 INFO - 'halt_on_failure': False, 15:14:54 INFO - 'name': 'check_screen_resolution'},), 15:14:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:14:54 INFO - 'cmd': ('bash', 15:14:54 INFO - '-c', 15:14:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:14:54 INFO - 'enabled': True, 15:14:54 INFO - 'halt_on_failure': False, 15:14:54 INFO - 'name': 'check_screen_resolution'},), 15:14:54 INFO - 'run_cmd_checks_enabled': True, 15:14:54 INFO - 'suite': 'dromaeojs-e10s', 15:14:54 INFO - 'system_bits': '32', 15:14:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:14:54 INFO - 'title': 't-yosemite-r7-0034', 15:14:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:14:54 INFO - 'use_talos_json': True, 15:14:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:14:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:14:54 INFO - 'work_dir': 'build'} 15:14:54 INFO - [mozharness: 2017-05-24 22:14:54.823056Z] Running clobber step. 15:14:54 INFO - Running pre-action listener: _resource_record_pre_action 15:14:54 INFO - Running main action method: clobber 15:14:54 INFO - rmtree: /builds/slave/test/build 15:14:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:14:58 INFO - Running post-action listener: _resource_record_post_action 15:14:58 INFO - [mozharness: 2017-05-24 22:14:58.355347Z] Finished clobber step (success) 15:14:58 INFO - [mozharness: 2017-05-24 22:14:58.355480Z] Running read-buildbot-config step. 15:14:58 INFO - Running pre-action listener: _resource_record_pre_action 15:14:58 INFO - Running main action method: read_buildbot_config 15:14:58 INFO - Using buildbot properties: 15:14:58 INFO - { 15:14:58 INFO - "project": "", 15:14:58 INFO - "product": "firefox", 15:14:58 INFO - "who": "ryanvm@gmail.com", 15:14:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 15:14:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:14:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 15:14:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:14:58 INFO - "stage_platform": "macosx64", 15:14:58 INFO - "basedir": "/builds/slave/test", 15:14:58 INFO - "buildnumber": 79, 15:14:58 INFO - "platform": "yosemite_r7", 15:14:58 INFO - "parent_task_id": "K5UT7hidRdqbzdcRjWvo3w", 15:14:58 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:14:58 INFO - "slavebuilddir": "test", 15:14:58 INFO - "taskId": "bMzAdCSeQiKTbvDwHk5KuQ", 15:14:58 INFO - "branch": "mozilla-central", 15:14:58 INFO - "script_repo_revision": "production", 15:14:58 INFO - "revision": "934ff2cb74b00bb92a7ab4f39aeaa014a9075a67", 15:14:58 INFO - "slavename": "t-yosemite-r7-0034", 15:14:58 INFO - "repo_path": "mozilla-central" 15:14:58 INFO - } 15:14:58 INFO - Finding installer, test and symbols from parent task. 15:14:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bMzAdCSeQiKTbvDwHk5KuQ'}, attempt #1 15:14:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bMzAdCSeQiKTbvDwHk5KuQ'}, attempt #1 15:14:59 INFO - Task dependencies: IPy7WKbzTPKKrgy-qQqtnw K5UT7hidRdqbzdcRjWvo3w 15:14:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w'}, attempt #1 15:15:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:15:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/test_packages.json 15:15:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/target.crashreporter-symbols.zip 15:15:00 INFO - Running post-action listener: _resource_record_post_action 15:15:00 INFO - [mozharness: 2017-05-24 22:15:00.073653Z] Finished read-buildbot-config step (success) 15:15:00 INFO - [mozharness: 2017-05-24 22:15:00.073770Z] Running download-and-extract step. 15:15:00 INFO - Running pre-action listener: _resource_record_pre_action 15:15:00 INFO - Running main action method: download_and_extract 15:15:00 INFO - mkdir: /builds/slave/test/build/tests 15:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:00 INFO - trying https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:15:00 INFO - Downloading https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/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 15:15:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/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 15:15:00 INFO - Downloaded 1325 bytes. 15:15:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:15:00 INFO - Using the following test package requirements: 15:15:00 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:15:00 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:15:00 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:15:00 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:15:00 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'jsshell-mac.zip'], 15:15:00 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:15:00 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:15:00 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:15:00 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:15:00 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:15:00 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:00 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:15:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:15:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:15:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:15:00 INFO - Fetch https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:15:02 INFO - Content-Length response header: 57625567 15:15:02 INFO - Bytes received: 57625567 15:15:21 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 15:15:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:15:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:15:21 INFO - Fetch https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:15:23 INFO - Content-Length response header: 57625567 15:15:23 INFO - Bytes received: 57625567 15:15:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:15:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:15:41 INFO - Fetch https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:15:43 INFO - Content-Length response header: 14411859 15:15:43 INFO - Bytes received: 14411859 15:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:43 INFO - trying https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:15:43 INFO - Downloading https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/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 15:15:45 INFO - Downloaded 68099918 bytes. 15:15:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:15:45 INFO - mkdir: /builds/slave/test/properties 15:15:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:15:45 INFO - Writing to file /builds/slave/test/properties/build_url 15:15:45 INFO - Contents: 15:15:45 INFO - build_url:https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:15:45 INFO - Running post-action listener: _resource_record_post_action 15:15:45 INFO - Running post-action listener: set_extra_try_arguments 15:15:45 INFO - [mozharness: 2017-05-24 22:15:45.496970Z] Finished download-and-extract step (success) 15:15:45 INFO - [mozharness: 2017-05-24 22:15:45.497127Z] Running populate-webroot step. 15:15:45 INFO - Running pre-action listener: _resource_record_pre_action 15:15:45 INFO - Running main action method: populate_webroot 15:15:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:15:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:15:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:15:45 INFO - '--tppagecycles', 15:15:45 INFO - '7'], 15:15:45 INFO - 'tests': ['tsvgm']}}, 15:15:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:15:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:15:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:15:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:15:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:15:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:15:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['damp', 'tps']}, 15:15:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'tests': ['damp', 'tps']}, 15:15:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['dromaeo_dom']}, 15:15:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:15:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:15:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:15:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:15:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['a11yr', 15:15:45 INFO - 'ts_paint', 15:15:45 INFO - 'tpaint', 15:15:45 INFO - 'sessionrestore', 15:15:45 INFO - 'sessionrestore_no_auto_restore', 15:15:45 INFO - 'tabpaint']}, 15:15:45 INFO - 'other-e10s': {'tests': ['a11yr', 15:15:45 INFO - 'ts_paint', 15:15:45 INFO - 'tpaint', 15:15:45 INFO - 'sessionrestore', 15:15:45 INFO - 'sessionrestore_no_auto_restore', 15:15:45 INFO - 'tabpaint']}, 15:15:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:15:45 INFO - 'ts_paint', 15:15:45 INFO - 'tpaint', 15:15:45 INFO - 'sessionrestore', 15:15:45 INFO - 'sessionrestore_no_auto_restore', 15:15:45 INFO - 'tabpaint']}, 15:15:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:15:45 INFO - 'ts_paint', 15:15:45 INFO - 'tpaint', 15:15:45 INFO - 'sessionrestore', 15:15:45 INFO - 'sessionrestore_no_auto_restore', 15:15:45 INFO - 'tabpaint']}, 15:15:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['a11yr', 15:15:45 INFO - 'ts_paint', 15:15:45 INFO - 'tpaint', 15:15:45 INFO - 'sessionrestore', 15:15:45 INFO - 'sessionrestore_no_auto_restore', 15:15:45 INFO - 'tabpaint']}, 15:15:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['a11yr', 15:15:45 INFO - 'ts_paint', 15:15:45 INFO - 'tpaint', 15:15:45 INFO - 'sessionrestore', 15:15:45 INFO - 'sessionrestore_no_auto_restore', 15:15:45 INFO - 'tabpaint']}, 15:15:45 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:15:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:15:45 INFO - 'bloom_basic_ref']}, 15:15:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['tsvgx', 15:15:45 INFO - 'tsvgr_opacity', 15:15:45 INFO - 'tart', 15:15:45 INFO - 'tscrollx', 15:15:45 INFO - 'cart', 15:15:45 INFO - 'tsvg_static']}, 15:15:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:15:45 INFO - 'tsvgr_opacity', 15:15:45 INFO - 'tart', 15:15:45 INFO - 'tscrollx', 15:15:45 INFO - 'cart', 15:15:45 INFO - 'tsvg_static']}, 15:15:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'talos_options': ['--disable-e10s'], 15:15:45 INFO - 'tests': ['tp5o']}, 15:15:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:15:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'talos_options': ['--disable-e10s', 15:15:45 INFO - '--xperf_path', 15:15:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:15:45 INFO - 'tests': ['tp5n']}, 15:15:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:15:45 INFO - 'talos_options': ['--xperf_path', 15:15:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:15:45 INFO - 'tests': ['tp5n']}}, 15:15:45 INFO - 'talos.zip': {'path': '', 15:15:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:15:45 INFO - Running post-action listener: _resource_record_post_action 15:15:45 INFO - [mozharness: 2017-05-24 22:15:45.519816Z] Finished populate-webroot step (success) 15:15:45 INFO - [mozharness: 2017-05-24 22:15:45.519983Z] Running create-virtualenv step. 15:15:45 INFO - Running pre-action listener: _resource_record_pre_action 15:15:45 INFO - Running main action method: create_virtualenv 15:15:45 INFO - Creating virtualenv /builds/slave/test/build/venv 15:15:45 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 15:15:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:15:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:15:45 INFO - Using real prefix '/tools/python27' 15:15:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:15:46 INFO - Installing distribute.............................................................................................................................................................................................done. 15:15:49 INFO - Installing pip.................done. 15:15:49 INFO - Return code: 0 15:15:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:15:49 INFO - Reading from file tmpfile_stdout 15:15:49 INFO - Output received: 15:15:49 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) 15:15:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:15: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')]} 15:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:15:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:15:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:50 INFO - 'HOME': '/Users/cltbld', 15:15:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:50 INFO - 'LOGNAME': 'cltbld', 15:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:50 INFO - 'MOZ_NO_REMOTE': '1', 15:15:50 INFO - 'NO_EM_RESTART': '1', 15:15:50 INFO - 'PAGER': '/bin/cat', 15:15:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:50 INFO - 'PWD': '/builds/slave/test', 15:15:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:50 INFO - 'SHELL': '/bin/bash', 15:15:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:15:50 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:15:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:50 INFO - 'USER': 'cltbld', 15:15:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:50 INFO - 'XPC_FLAGS': '0x0', 15:15:50 INFO - 'XPC_SERVICE_NAME': '0', 15:15:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:50 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 15:15:50 INFO - Cleaning up... 15:15:50 INFO - Return code: 0 15:15:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:51 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 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:15:51 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 15:15:51 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 15:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:51 INFO - 'HOME': '/Users/cltbld', 15:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:51 INFO - 'LOGNAME': 'cltbld', 15:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:51 INFO - 'MOZ_NO_REMOTE': '1', 15:15:51 INFO - 'NO_EM_RESTART': '1', 15:15:51 INFO - 'PAGER': '/bin/cat', 15:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:51 INFO - 'PWD': '/builds/slave/test', 15:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:51 INFO - 'SHELL': '/bin/bash', 15:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:15:51 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:51 INFO - 'USER': 'cltbld', 15:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:51 INFO - 'XPC_FLAGS': '0x0', 15:15:51 INFO - 'XPC_SERVICE_NAME': '0', 15:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:51 INFO - Downloading/unpacking psutil>=3.1.1 15:15: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 15:15: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 15:15: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 15:15: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 15:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:15:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:53 INFO - Installing collected packages: psutil 15:15:53 INFO - Running setup.py install for psutil 15:15:53 INFO - building 'psutil._psutil_osx' extension 15:15:53 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 15:15:54 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 15:15:54 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 15:15:54 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 15:15:54 INFO - building 'psutil._psutil_posix' extension 15:15:54 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 15:15:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:15:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:15:54 INFO - ^ 15:15:54 INFO - 1 warning generated. 15:15:54 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 15:15:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:54 INFO - Successfully installed psutil 15:15:54 INFO - Cleaning up... 15:15:54 INFO - Return code: 0 15:15:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:15:54 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')]} 15:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:54 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')]} 15:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:54 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 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:15:54 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 15:15:54 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 15:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:54 INFO - 'HOME': '/Users/cltbld', 15:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:54 INFO - 'LOGNAME': 'cltbld', 15:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:54 INFO - 'MOZ_NO_REMOTE': '1', 15:15:54 INFO - 'NO_EM_RESTART': '1', 15:15:54 INFO - 'PAGER': '/bin/cat', 15:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:54 INFO - 'PWD': '/builds/slave/test', 15:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:54 INFO - 'SHELL': '/bin/bash', 15:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:15:54 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:54 INFO - 'USER': 'cltbld', 15:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:54 INFO - 'XPC_FLAGS': '0x0', 15:15:54 INFO - 'XPC_SERVICE_NAME': '0', 15:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:15:56 INFO - Installing collected packages: mozsystemmonitor 15:15:56 INFO - Running setup.py install for mozsystemmonitor 15:15:56 INFO - Successfully installed mozsystemmonitor 15:15:56 INFO - Cleaning up... 15:15:56 INFO - Return code: 0 15:15:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15: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')]} 15:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:15:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:15:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:57 INFO - 'HOME': '/Users/cltbld', 15:15:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:57 INFO - 'LOGNAME': 'cltbld', 15:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:57 INFO - 'MOZ_NO_REMOTE': '1', 15:15:57 INFO - 'NO_EM_RESTART': '1', 15:15:57 INFO - 'PAGER': '/bin/cat', 15:15:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:57 INFO - 'PWD': '/builds/slave/test', 15:15:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:57 INFO - 'SHELL': '/bin/bash', 15:15:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:15:57 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:15:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:57 INFO - 'USER': 'cltbld', 15:15:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:57 INFO - 'XPC_FLAGS': '0x0', 15:15:57 INFO - 'XPC_SERVICE_NAME': '0', 15:15:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:57 INFO - Downloading/unpacking jsonschema==2.5.1 15:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:15:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:58 INFO - Downloading functools32-3.2.3-2.tar.gz 15:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:15:59 INFO - warning: no files found matching '*.txt' 15:15:59 INFO - no previously-included directories found matching 'build' 15:15:59 INFO - no previously-included directories found matching 'dist' 15:15:59 INFO - no previously-included directories found matching '.git*' 15:15:59 INFO - Installing collected packages: jsonschema, functools32 15:15:59 INFO - Running setup.py install for functools32 15:15:59 INFO - warning: no files found matching '*.txt' 15:15:59 INFO - no previously-included directories found matching 'build' 15:15:59 INFO - no previously-included directories found matching 'dist' 15:15:59 INFO - no previously-included directories found matching '.git*' 15:15:59 INFO - Successfully installed jsonschema functools32 15:15:59 INFO - Cleaning up... 15:15:59 INFO - Return code: 0 15:15:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:15:59 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')]} 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:59 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')]} 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:59 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 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:15:59 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 15:15:59 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 15:15:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:15:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:59 INFO - 'HOME': '/Users/cltbld', 15:15:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:59 INFO - 'LOGNAME': 'cltbld', 15:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:59 INFO - 'MOZ_NO_REMOTE': '1', 15:15:59 INFO - 'NO_EM_RESTART': '1', 15:15:59 INFO - 'PAGER': '/bin/cat', 15:15:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:59 INFO - 'PWD': '/builds/slave/test', 15:15:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:59 INFO - 'SHELL': '/bin/bash', 15:15:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:15:59 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:15:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:59 INFO - 'USER': 'cltbld', 15:15:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:59 INFO - 'XPC_FLAGS': '0x0', 15:15:59 INFO - 'XPC_SERVICE_NAME': '0', 15:15:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:15:59 INFO - Cleaning up... 15:15:59 INFO - Return code: 0 15:15:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:15:59 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')]} 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:59 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')]} 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:59 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 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:15:59 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 15:15:59 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 15:15:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:15:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:59 INFO - 'HOME': '/Users/cltbld', 15:15:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:59 INFO - 'LOGNAME': 'cltbld', 15:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:59 INFO - 'MOZ_NO_REMOTE': '1', 15:15:59 INFO - 'NO_EM_RESTART': '1', 15:15:59 INFO - 'PAGER': '/bin/cat', 15:15:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:59 INFO - 'PWD': '/builds/slave/test', 15:15:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:59 INFO - 'SHELL': '/bin/bash', 15:15:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:15:59 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:15:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:59 INFO - 'USER': 'cltbld', 15:15:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:59 INFO - 'XPC_FLAGS': '0x0', 15:15:59 INFO - 'XPC_SERVICE_NAME': '0', 15:15:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:59 INFO - Downloading/unpacking blobuploader==1.2.4 15:15: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 15:15: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 15:15: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 15:15: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 15:16:01 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16: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 15:16: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 15:16: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 15:16: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 15:16:02 INFO - Downloading docopt-0.6.1.tar.gz 15:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:02 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:02 INFO - Running setup.py install for blobuploader 15:16:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:02 INFO - Running setup.py install for requests 15:16:03 INFO - Running setup.py install for docopt 15:16:03 INFO - Successfully installed blobuploader requests docopt 15:16:03 INFO - Cleaning up... 15:16:03 INFO - Return code: 0 15:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16: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')]} 15:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:03 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 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:16:03 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 15:16:03 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 15:16:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:16:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:03 INFO - 'HOME': '/Users/cltbld', 15:16:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:03 INFO - 'LOGNAME': 'cltbld', 15:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:03 INFO - 'MOZ_NO_REMOTE': '1', 15:16:03 INFO - 'NO_EM_RESTART': '1', 15:16:03 INFO - 'PAGER': '/bin/cat', 15:16:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:03 INFO - 'PWD': '/builds/slave/test', 15:16:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:03 INFO - 'SHELL': '/bin/bash', 15:16:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:16:03 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:16:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:03 INFO - 'USER': 'cltbld', 15:16:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:03 INFO - 'XPC_FLAGS': '0x0', 15:16:03 INFO - 'XPC_SERVICE_NAME': '0', 15:16:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:03 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-REFnoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:03 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-YvozfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:03 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ALS7p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:03 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-12C369-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:03 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-P9mhDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-OpZLtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-PKdikc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-fQocr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GnFUcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-n8VOPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-960oN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-mc1KZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-AT8j6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:04 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ybJYU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:05 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-RMyOC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:05 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qfRMmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:05 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-VA5_Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:05 INFO - Running setup.py install for manifestparser 15:16:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:05 INFO - Running setup.py install for mozcrash 15:16:05 INFO - Running setup.py install for mozdebug 15:16:05 INFO - Running setup.py install for mozdevice 15:16:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Running setup.py install for mozfile 15:16:06 INFO - Running setup.py install for mozhttpd 15:16:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Running setup.py install for mozinfo 15:16:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Running setup.py install for mozInstall 15:16:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Running setup.py install for mozleak 15:16:06 INFO - Running setup.py install for mozlog 15:16:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:06 INFO - Running setup.py install for moznetwork 15:16:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:07 INFO - Running setup.py install for mozprocess 15:16:07 INFO - Running setup.py install for mozprofile 15:16:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:07 INFO - Running setup.py install for mozrunner 15:16:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:07 INFO - Running setup.py install for mozscreenshot 15:16:07 INFO - Running setup.py install for moztest 15:16:07 INFO - Running setup.py install for mozversion 15:16:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:08 INFO - Cleaning up... 15:16:08 INFO - Return code: 0 15:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:08 INFO - 'HOME': '/Users/cltbld', 15:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:08 INFO - 'LOGNAME': 'cltbld', 15:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:08 INFO - 'MOZ_NO_REMOTE': '1', 15:16:08 INFO - 'NO_EM_RESTART': '1', 15:16:08 INFO - 'PAGER': '/bin/cat', 15:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:08 INFO - 'PWD': '/builds/slave/test', 15:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:08 INFO - 'SHELL': '/bin/bash', 15:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:16:08 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:08 INFO - 'USER': 'cltbld', 15:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:08 INFO - 'XPC_FLAGS': '0x0', 15:16:08 INFO - 'XPC_SERVICE_NAME': '0', 15:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-l1NOho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:08 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)) 15:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EpA3bl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:08 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)) 15:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-pGwLfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:08 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)) 15:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bNIKgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 15:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_Ch7_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:08 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)) 15:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ntJfgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-tsiR7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-eau0Du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:09 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)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-yUJDqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qLd6OE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:09 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)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-zsWkbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:09 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)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-v4p9kC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:09 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)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-dH1ZLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:09 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)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-RDPWkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:09 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)) 15:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:09 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-b31Wol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:10 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-pnCg_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:10 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-xo_E6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:16:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:11 INFO - Downloading blessings-1.6.tar.gz 15:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:16:11 INFO - Installing collected packages: blessings 15:16:11 INFO - Running setup.py install for blessings 15:16:12 INFO - Successfully installed blessings 15:16:12 INFO - Cleaning up... 15:16:12 INFO - Return code: 0 15:16:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:16:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:12 INFO - Reading from file tmpfile_stdout 15:16:12 INFO - Current package versions: 15:16:12 INFO - blessings == 1.6 15:16:12 INFO - blobuploader == 1.2.4 15:16:12 INFO - docopt == 0.6.1 15:16:12 INFO - functools32 == 3.2.3-2 15:16:12 INFO - jsonschema == 2.5.1 15:16:12 INFO - manifestparser == 1.1 15:16:12 INFO - mozInstall == 1.12 15:16:12 INFO - mozcrash == 1.0 15:16:12 INFO - mozdebug == 0.1 15:16:12 INFO - mozdevice == 0.50 15:16:12 INFO - mozfile == 1.2 15:16:12 INFO - mozhttpd == 0.7 15:16:12 INFO - mozinfo == 0.9 15:16:12 INFO - mozleak == 0.1 15:16:12 INFO - mozlog == 3.4 15:16:12 INFO - moznetwork == 0.27 15:16:12 INFO - mozprocess == 0.25 15:16:12 INFO - mozprofile == 0.28 15:16:12 INFO - mozrunner == 6.13 15:16:12 INFO - mozscreenshot == 0.1 15:16:12 INFO - mozsystemmonitor == 0.3 15:16:12 INFO - moztest == 0.8 15:16:12 INFO - mozversion == 1.4 15:16:12 INFO - psutil == 3.1.1 15:16:12 INFO - requests == 1.2.3 15:16:12 INFO - wsgiref == 0.1.2 15:16:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:12 INFO - 'HOME': '/Users/cltbld', 15:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:12 INFO - 'LOGNAME': 'cltbld', 15:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:12 INFO - 'MOZ_NO_REMOTE': '1', 15:16:12 INFO - 'NO_EM_RESTART': '1', 15:16:12 INFO - 'PAGER': '/bin/cat', 15:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:12 INFO - 'PWD': '/builds/slave/test', 15:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:12 INFO - 'SHELL': '/bin/bash', 15:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:16:12 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:12 INFO - 'USER': 'cltbld', 15:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:12 INFO - 'XPC_FLAGS': '0x0', 15:16:12 INFO - 'XPC_SERVICE_NAME': '0', 15:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:12 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)) 15:16:12 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)) 15:16:12 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)) 15:16:12 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)) 15:16:12 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)) 15:16:12 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)) 15:16:12 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)) 15:16:12 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)) 15:16:12 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)) 15:16:12 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:16:12 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 15:16:12 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 15:16:12 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 15:16:12 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 15:16:14 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:16:14 INFO - Installing collected packages: marionette-driver 15:16:14 INFO - Running setup.py install for marionette-driver 15:16:14 INFO - Successfully installed marionette-driver 15:16:14 INFO - Cleaning up... 15:16:14 INFO - Return code: 0 15:16:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10970ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e1caf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, '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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:16:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:16:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:16:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:14 INFO - 'HOME': '/Users/cltbld', 15:16:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:14 INFO - 'LOGNAME': 'cltbld', 15:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:14 INFO - 'MOZ_NO_REMOTE': '1', 15:16:14 INFO - 'NO_EM_RESTART': '1', 15:16:14 INFO - 'PAGER': '/bin/cat', 15:16:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:14 INFO - 'PWD': '/builds/slave/test', 15:16:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:14 INFO - 'SHELL': '/bin/bash', 15:16:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:16:14 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:16:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:14 INFO - 'USER': 'cltbld', 15:16:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:14 INFO - 'XPC_FLAGS': '0x0', 15:16:14 INFO - 'XPC_SERVICE_NAME': '0', 15:16:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:16:14 INFO - Cleaning up... 15:16:14 INFO - Return code: 0 15:16:14 INFO - Running post-action listener: _resource_record_post_action 15:16:14 INFO - Running post-action listener: _start_resource_monitoring 15:16:14 INFO - Starting resource monitoring. 15:16:14 INFO - [mozharness: 2017-05-24 22:16:14.749914Z] Finished create-virtualenv step (success) 15:16:14 INFO - [mozharness: 2017-05-24 22:16:14.750922Z] Running install step. 15:16:14 INFO - Running pre-action listener: _resource_record_pre_action 15:16:14 INFO - Running main action method: install 15:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:15 INFO - Reading from file tmpfile_stdout 15:16:15 INFO - Detecting whether we're running mozinstall >=1.0... 15:16:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:16:15 INFO - Reading from file tmpfile_stdout 15:16:15 INFO - Output received: 15:16:15 INFO - Usage: mozinstall [options] installer 15:16:15 INFO - Options: 15:16:15 INFO - -h, --help show this help message and exit 15:16:15 INFO - -d DEST, --destination=DEST 15:16:15 INFO - Directory to install application into. [default: 15:16:15 INFO - "/builds/slave/test"] 15:16:15 INFO - --app=APP Application being installed. [default: firefox] 15:16:15 INFO - mkdir: /builds/slave/test/build/application 15:16:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:16:37 INFO - Reading from file tmpfile_stdout 15:16:37 INFO - Output received: 15:16:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:16:37 INFO - Running post-action listener: _resource_record_post_action 15:16:37 INFO - [mozharness: 2017-05-24 22:16:37.235756Z] Finished install step (success) 15:16:37 INFO - [mozharness: 2017-05-24 22:16:37.235928Z] Running run-tests step. 15:16:37 INFO - Running pre-action listener: _resource_record_pre_action 15:16:37 INFO - Running main action method: run_tests 15:16:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:16:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:16:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:16:37 INFO - 2017-05-24 15:16:37.251 screenresolution[1622:7559] starting screenresolution argv=screenresolution get 15:16:37 INFO - 2017-05-24 15:16:37.254 screenresolution[1622:7559] Display 0: 1600x1200x32@60 15:16:37 INFO - 2017-05-24 15:16:37.260 screenresolution[1623:7561] starting screenresolution argv=screenresolution list 15:16:37 INFO - Available Modes on Display 0 15:16:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:16:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:16:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:16:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:16:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:16:37 INFO - Intel Iris: 15:16:37 INFO - Chipset Model: Intel Iris 15:16:37 INFO - Type: GPU 15:16:37 INFO - Bus: Built-In 15:16:37 INFO - VRAM (Dynamic, Max): 1536 MB 15:16:37 INFO - Vendor: Intel (0x8086) 15:16:37 INFO - Device ID: 0x0a2e 15:16:37 INFO - Revision ID: 0x0009 15:16:37 INFO - Displays: 15:16:37 INFO - Display: 15:16:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:16:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:16:37 INFO - Display Serial Number: 1600x1200 60 15:16:37 INFO - Main Display: Yes 15:16:37 INFO - Mirror: Off 15:16:37 INFO - Online: Yes 15:16:37 INFO - Rotation: Supported 15:16:37 INFO - Return code: 0 15:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:16:37 INFO - Python 2.7.3 15:16:37 INFO - Return code: 0 15:16:37 INFO - grabbing minidump binary from tooltool 15:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941ad30>, '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 0x1093cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:16:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:16:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:16:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:16:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:16:37 INFO - Return code: 0 15:16:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:16:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:16:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:16:37 INFO - ENV: RUST_BACKTRACE is now 1 15:16:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:16:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 15:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 15:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rMmHS2vtyY/Render', 15:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:37 INFO - 'HOME': '/Users/cltbld', 15:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:37 INFO - 'LOGNAME': 'cltbld', 15:16:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:16:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:37 INFO - 'MOZ_NO_REMOTE': '1', 15:16:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:16:37 INFO - 'NO_EM_RESTART': '1', 15:16:37 INFO - 'PAGER': '/bin/cat', 15:16:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:37 INFO - 'PWD': '/builds/slave/test', 15:16:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:37 INFO - 'RUST_BACKTRACE': '1', 15:16:37 INFO - 'SHELL': '/bin/bash', 15:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWIlgEJTbB/Listeners', 15:16:37 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:37 INFO - 'USER': 'cltbld', 15:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:37 INFO - 'XPC_FLAGS': '0x0', 15:16:37 INFO - 'XPC_SERVICE_NAME': '0', 15:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 15:16:37 INFO - mozversion application_buildid: 20170524133723 15:16:37 INFO - mozversion application_changeset: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 15:16:37 INFO - mozversion application_display_name: Nightly 15:16:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:16:37 INFO - mozversion application_name: Firefox 15:16:37 INFO - mozversion application_remotingname: firefox 15:16:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:16:37 INFO - mozversion application_vendor: Mozilla 15:16:37 INFO - mozversion application_version: 55.0a1 15:16:37 INFO - mozversion platform_buildid: 20170524133723 15:16:37 INFO - mozversion platform_changeset: 934ff2cb74b00bb92a7ab4f39aeaa014a9075a67 15:16:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:16:37 INFO - mozversion platform_version: 55.0a1 15:16:37 INFO - using testdate: 1495664197 15:16:37 INFO - actual date: 1495664197 15:16:37 INFO - starting webserver on 'localhost:49232' 15:16:37 INFO - SUITE-START | Running 2 tests 15:16:37 INFO - TEST-START | dromaeo_css 15:16:37 INFO - operating with platform_type : mac_ 15:16:37 INFO - Initialising browser for dromaeo_css test... 15:16:37 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp5hCNLb/profile 15:16:37 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 15:17:11 INFO - TEST-INFO | 1633: killed by SIGTERM 15:17:11 INFO - Browser initialized. 15:17:11 INFO - Running cycle 1/1 for dromaeo_css test... 15:17:11 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp5hCNLb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:17:13 INFO - PID 1640 | 2017-05-24 15:17:13.775 plugin-container[1641:8031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 15:17:13 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:13 INFO - PID 1640 | 2017-05-24 15:17:13.776 plugin-container[1641:8031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 15:17:13 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:13 INFO - PID 1640 | 2017-05-24 15:17:13.776 plugin-container[1641:8031] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:17:14 INFO - PID 1640 | 2017-05-24 15:17:14.188 plugin-container[1642:8074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 15:17:14 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:14 INFO - PID 1640 | 2017-05-24 15:17:14.188 plugin-container[1642:8074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 15:17:14 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:14 INFO - PID 1640 | 2017-05-24 15:17:14.188 plugin-container[1642:8074] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:17:23 INFO - PID 1640 | RSS: Main: 246403072 15:17:23 INFO - PID 1640 | 15:20:24 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49232/tests/dromaeo/cssquery-ext.html) 15:20:26 INFO - PID 1640 | RSS: Main: 257863680 15:20:26 INFO - PID 1640 | 15:23:28 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-ext.html (next: http://localhost:49232/tests/dromaeo/cssquery-jquery.html) 15:23:28 INFO - PID 1640 | RSS: Main: 271392768 15:23:28 INFO - PID 1640 | 15:26:30 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49232/tests/dromaeo/cssquery-mootools.html) 15:26:31 INFO - PID 1640 | RSS: Main: 273510400 15:26:31 INFO - PID 1640 | 15:29:32 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49232/tests/dromaeo/cssquery-prototype.html) 15:29:33 INFO - PID 1640 | RSS: Main: 277200896 15:29:33 INFO - PID 1640 | 15:32:36 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49232/tests/dromaeo/cssquery-yui.html) 15:32:36 INFO - PID 1640 | RSS: Main: 277393408 15:32:36 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-yui.html (next: http://localhost:49232/tests/dromaeo/cssquery-dojo.html) 15:35:38 INFO - PID 1640 | RSS: Main: 279150592 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | __start_tp_report 15:35:38 INFO - PID 1640 | _x_x_mozilla_page_load 15:35:38 INFO - PID 1640 | _x_x_mozilla_page_load_details 15:35:38 INFO - PID 1640 | |i|pagename|runs| 15:35:38 INFO - PID 1640 | |0;dojo.html;2983.9705301670924;2998.7900846940724;3030.045535656268;3057.831819249942;3076.369344284421;4978.800847966077;5049.671771334865;5130.461301563333;5170.198619214018;5176.689398636085;4302.677299202556;5056.165732654108;5068.315777370054;5162.354705661793;5179.922301165476;3722.553293604771;3742.4386342048774;4576.199165146086;4626.7455289959125;4711.528847115278;4556.3621093047;4680.017196388762;4691.272852707836;4707;4711.316859055452;4490.39379683745;4588.885277868047;4647.535246475325;4685.969086800898;4736.052789442108;4529;4610.9077818443775;4648.953510464886;4656.627469802442;4704.435467743892;4103.384492326145;4606.355110284563;4610.101030299093;4619.12236675034;4686.297055441711;4980.402351717781;4996.7501624918605;5118.07874582575;5146.176611742141;5148.304978827885;4271;5640.435956404368;5662.17898404729;5715.571332150105;5719.799807006776;5371.113766228567;5859.794907178229;5861.472467477947;5884.117382392676;5915.822525324204;5430.5927055471;5434.64674796137;5450.591205659592;5462.0441422751255;5463.989162004962;5804.680742559115;5838.095095260242;5849.707514624251;5916.230889984275;5964.224650795456;5541.722913854372;5603.663780173202;5617.662940223599;5716.57125715573;5728.796952639909;5771.422857714195;5773.509251713652;5856.180134781134;5864.736086876101;5895.55783316253;5687.800926967537;5690.430956904359;5780;5794.30468343793;5818.8254352369495;5025.84922452327;5584.692841893735;5779.768809247667;5796.101604251347;5847.795327163529;4489.820407183676;4593.977030114895;4637.721736695809;4675.345451636774;4712.528747125329;3442.4836274558515;4171.374293855885;4178.561251068655;4220.092680073799;4254.87235382939;5259.266660006026;5381.838544843661;5384.461553844584;5393.919091213635;5404.621676482609;5675.2054712340305;5749.425057494217;5758.395368486333;5804.1293805929445;5880.617759845596;4858;5078.603384069336;5218.765155568009;5387.946120538744;5421.213923981;5108.56577190943;5119.6160287978555;5121.052605268037;5121.180611102206;5150.587952963829;5809.622374545641;5868.735906884286;5914.526837852962;5983.34183239852;5993.220881285405;5403.13549832025;5870.295564532284;5873.882522349614;5901.940980590139;5944.346121926671;5934.169216309634;5935.5548333875995;5945.256842894638;5949.940500595112;5964.940350596612;5288.9735551322;5540.390557038803;5645.463680950304;5723.82661554372;5761;5794.246747922912;5850.268716410449;5878.3239927407185;5890.7054647266605;5915.970420147872;5624.437556244343;5670.74481648335;5701.059325211294;5715.342735585461;5737.74180161902;5559.610827242216;5576.86057848557;5609.21470994053;5648.068068768607;5698.1452782083015;5448.809291674773;5567.721613919369;5580.77676892936;5593.692346920959;5607.859803504782;4670.859874203779;5672.631278966854;5672.829815105553;5716.628419152742;5722.227499287543;5378.489043540857;5491;5500.28496295479;5561.110222364564;5592.832215033555 15:35:38 INFO - PID 1640 | |1;ext.html;632.5572099530328;735.4263674334021;779.5751315533032;1120.3893877836579;1186.8397766301546;35908.820455897716;36870.44694329587;36901;36911.81544092276;36935.445968310494;36116;36463.45304820423;36509.817450912706;36696.81651591745;36707.63292367075;7340.816479588013;7447;7706;7956.880646790303;7963.3231175350165;7917.247861453153;7984.6007699615075;8010.5594192319395;8032.718854840082;8034.317083047948;7982.3614110871285;8001.639926203335;8034.116247212802;8051.59742012897;8063.314618257456;7215.242399548024;7647.311741943252;7989.280964713174;8018.67925282988;8061.798455038671;7761.45669803114;7777.183395743478;7845.333146682538;7846.607669616496;7888.40836937232;35172.47241291383;35240.11899702502;35251.4712279316;35307.646923530694;35416.29167416637;3223.774335796495;3232.1273256220925;3256.1208473711963;3269.29710112327;3270.4930735735998;28604.713952860413;28934.710652893624;28938.27654308638;28955.71044289572;29053;2978.8063775854716;3010.32267739759;3024.364883374472;3025.9546106808416;3028;241234;244893;291721;292805;294451.5277423599;44644.77677611591;45483.545164548595;45498;45525.77237113793;45665;47219;47312.290315645296;47326.053478930226;47460.05079898452;50848;13563;13657.112287701466;13696.178229306273;13726.65683357924;13759.243241621807;10966.232363734622;10995.395253260987;11063.557457701601;11068.505751723575;11101.666949991513;12760.277362555937;12825.679358016125;12872.16330938486;12872.356382180853;12955.935220323838;5809.912851307234;5838.241028666331;5871.941280587225;5889.852753681193;5897.292324921037;813.6419975210888;866.6793286484042;885.8538341173763;899.9550022498848;910.1717437132311;16334.264958076916;18260.904345739298;20959.8952005239;22500.32499025032;22784.772152278598;994.8806143262855;1013.5844303835393;1035.2028937717914;1038.1295717341557;1043.6973277749519;9922.858871229757;11185.217034807485;11786.998105160987;12012.519499220109;12043;289729;291977;292076;292649;294067;46849.76575117103;51003.48996510061;51226.74386628118;51470;51471.48528514667;1257.270782945889;1389.6109089454767;1402.6493376655835;1423.8576142385887;1428.2715814934459;22207.888960555094;22709.772902270764;23039.07843686234;23183.652245216;23227.767722322562;35881.82059089688;35895;35900.82049589735;35952;35983;267167;267802;267918.6604066967;269465;270161;33340.33319333648;33397.83301083479;33416.49875251922;33492.32061435128;33663.663363367035;40567.3914891271;41240.58759412367;41379.379309309785;42207.94480138021;43022;28277.29306767265;28823.56764648574;29260.707392925797;29835.403291933606;30138;24153.75846241515;28170;28959.42081158323;30150;30179.245518862204;34126.31747365089;34675.826620867745;35356.823215883756;36054;36123.63876361203;29447.411051779272;29657.665405057254;29953.313539073424;30084.24789380283;30123.849380752956;22300.88849555807;22657.433564161027;23115.422114447272;23404.531909362056;23505.529889401772 15:35:38 INFO - PID 1640 | |2;jquery.html;352.9276498317842;365.941449368101;398.8723608445297;404.2844165826487;415.10751883450575;18496;19534.51163720904;19852.60294794105;19956.201751929904;20008.29970951017;20836.583268334623;21072.367828965154;21132.683009754866;21269.57460850782;21325.146994120256;5307.964946835368;5508.338999320077;5763.625364351325;5774.2782152230975;5797.971010144944;4381.9561804381865;5664.745086471119;5665.206871038037;5667.433256674341;5677.602567820254;5588.189712491686;5595.972020139893;5630.7184640768;5645.040343141676;5649.943500565003;5491.725413729318;5575.832725018256;5628.592851787053;5630.60585758995;5633.746481408305;4975.233792003859;5001.924971125436;5025.623078269108;5027.069992051483;5051.924221136685;23811.047558097827;24038.879805601035;24131.63802542963;24151.516969660508;24390.26829195127;2074.7614024387085;2119.237074653139;2121.917821910821;2133.8506304558687;2146.1844499090244;16576.75134872978;16680.416185433452;16865.83134168655;17102.572435689082;17158.142092895305;1991.1139542903413;2020.1818263603263;2054.897255137237;2065.6901464780253;2068.4621998280404;137700;138877;140698.29650851781;141016;142314.28842855824;11468.713282168013;11616.651500455;11659.941700291614;11775.823362649568;11989;14798.926005369904;15315.387384504495;15376.461823836113;15377.001484710598;15455.768163477556;8484.236418722345;8484.490930544187;8586.22723954847;8612.397132200811;8727.519986400683;8199.590020498952;8225.341972642174;8244.628991695388;8395.454295470774;8400.243978042006;6109.17964003543;7275.345218930322;7365.926340736632;7424.215919030364;7481.428899931076;1835.6145209506153;1923.4806602217323;2165.155589320166;2171.7502487214174;2316.2703748319227;665.4776000839317;756.228646780281;760.9308920966051;788.239349028179;818.771842236645;18654.53363665892;19638.60722785565;19685.901570492057;19804.009799509968;20355.389338319874;891.7636826241052;910.6266430763355;914.6067191107754;933.6265493802533;933.8459154239611;8293.95853020731;8578.270846978077;10058.094771470604;10090.394576325443;10101.595936162472;528717;531969.3401532967;532051;535881;546703;116858.41570792091;118632.40683796698;120903;121412.39293803652;123913;1035.2753072849036;1068.8396740488988;1085.1156307609274;1087.124864484098;1094.485591771882;16921.407750728664;17115.400960966308;17168.914155429644;17444.563885902953;17635.470935871945;44267.778661107775;54706.726466367414;54939.72530137324;55295.72352138213;55634.44365556293;73246.63376683083;74162.62918685372;74164;74841;77986;7601.0878694557005;8235.876461852957;8312.5428101455;8360.749177524684;8723.389362744801;11234.943825280821;11328.830067549157;11389;13160.276184809927;13550.186988780704;11037.83443248368;11219.439028048728;11469.483873225892;11501.53993840237;11545.422728863356;8532.488050716976;10441.477926103817;10771.838422423512;10826.621068262888;10915.23593348458;11542.249753765978;11617.361045142598;11646.301221926711;11662.76674466489;11713.531458741896;8268.462549934235;8320.459170153921;8470.15298470148;8545.273651739672;8561.743147705578;11437.5431640558;11531;11622.476988535705;11646.708832278922;11740.178187526792 15:35:38 INFO - PID 1640 | |3;mootools.html;1139.8062329404002;1353.4586165533792;1366.5490388171897;1374.0656353679494;1376.841663208731;11123.221374503788;11253.774924501506;11817.23187992781;11848.644540663772;11912.642620721392;11656.825147622792;11663.591774287901;11698.181127321091;11843.230190037642;11856.940715296432;2411.4205195596824;2433.8052955763583;2440.3411079008656;2453.460238747473;2457.1522824625563;2392.880355982194;2409.2410890569504;2433.5376278507115;2436.244764123119;2450.963235551459;2390.103711108334;2415.04605680756;2427.672264244322;2429.0526694589125;2430.1130087518104;2303.4586872085047;2379.4646204604;2392.7966122879575;2393.940151496209;2405.4226985523524;2012.8590998630102;2101.7898210179014;2344.4959333743154;2366.7514910934447;2378.714554253501;11430.71423214418;11498.19512634116;11571.94214028933;11583.710407239887;11600.187986840925;4897.730624815634;6351.587146835441;6364.236291644986;6397.872042559169;6405.51958603102;3808.1622043150464;4026.315526360526;4029.8589549365925;4079.6124368185274;4082;1179.5281887245171;1185.2829038431796;1211.0674780419029;1218.0925210717983;1240.8883200511907;16161.353545858152;16267.10530920799;16442.917785411115;16448.177591120395;16455.588610284838;5264.5525130363585;5271.868203294872;5280.234366016983;5402.972985135049;5428.511433970962;11422.25755325901;11529.538818447169;11581.76836463266;11592.76814463706;11608.361540115206;5938.762449502058;6094.8171554853425;6117.816465506042;6167.074938759132;6182.6599537025895;5709.65742055478;5848.239728835224;5874.765009399662;5903.173555702205;5910.645361278337;4217.34631132175;4293.978530107392;4309.741415515079;4321.351797230378;4330.848420305274;1070.6038765656756;1230.4954968462887;1235.8640549539543;1252.3425201769144;1253.3106791264768;644.8162273752052;662.9801105966828;694.1323345817727;697.3444961735952;707.4199156691463;5748.597598168171;5937.376575459601;6005.609635373687;6042.335343112254;6996.6851491683;831.580052073711;834.3867257565685;842.8988521377352;844.2612713875359;854.9180205359589;3728.844058341923;3746.5878753337656;4194.937075943863;4494.415725955605;4764.95235047652;14633.560993170222;14637.780433293508;14931.328090235966;14969.550913472613;14982.325795339018;14240.359183836752;14663.120212787268;15039.097654140787;15041.247937603295;15043.924780376028;7996.440249182618;8181.468204566684;8246.793830154174;8300.584970751439;8357.247847693738;5962.37395073511;6557.704903279459;6733.932660673527;6776.186857577122;6799.796006119825;13091.869081309065;13414.597562073153;13527.256000920044;13543.255120968048;13565.38955746974;13677;13724.382402792098;13749.656258593615;13755.243461609707;13888.236147012012;8754.29965602763;8943.642254310014;9074.591643376196;9132.40639358466;9181.724548263563;10542.736431589272;10616.469176540988;10701.357918524913;10708.3574985498;10734.624288149877;8737.169968852948;8871.467711937305;8953.686620968236;8999.865002024844;9011.864822027806;8508.106648801786;8606.311495080254;8683.522406267715;8710.390272681105;8727.78180545466;7872.763817085496;7942.920570794449;7971.322437592869;8007.719729809429;8017.719379821679;5571.1086226203615;5690.430956904276;5717.17101020358;5769.769209231584;5901.1443340714495;5776.624519406225;5863.589548731547;5883.47048765596;5904.232449781501;5978 15:35:38 INFO - PID 1640 | |4;prototype.html;306.8542442339889;363.2136424640653;370.08773373634006;373.36155174651344;380.8952538052037;4179.770112643803;4214.452121224244;4901.509849015097;5127.846164615063;5182.352205974254;4003.539592946812;4995.675281106725;4995.950040499594;5002.474740152286;5083.440821509631;3600.9557228403864;3604.729645276602;3744.4383342498572;3775.7734535927934;3784.9053773655605;2957.275467510463;3732.757370770905;3787.242551489713;3812;3828.674562662177;3722.9069273268115;3754.6057663945303;3758.398656215006;3759.774413535197;3769.208466222097;3374.667006532406;3537.557805274341;3717.237966216919;3786.7159963002746;3794.260119276742;2705.47243287559;3098.2099564611103;3261.2010057536054;3278.8196649184283;3297.6207736110414;4792.281157826319;4795.20879054959;4827;4837.685550439211;4854.732989685566;3683.686886614641;4422.248217802949;4469.664775141878;4501.729896206205;4514.255147900592;5339.332583427072;5398.109311963522;5411.431799661018;5443;5467.535259502947;3770.4344348347695;3773;3775.3388509055926;3786.1291902862495;3791.165943492455;4142.420061191436;4664.017792438201;5518.19986102017;5683.744231509633;5720.6281591696825;3180.634227063871;3666.7799932004164;3763.8870833875026;3782.865140457852;3800.3349413852466;3481.1819222482695;3834.328992426314;3863.459115723801;3873.1479074603544;3881.5536213335827;4080.8775736727944;4469.910601787946;4477.70894891831;4480.843170489083;4485.686001979895;5308.787648494017;5434.619576629677;5443.455654434525;5513.9448605513435;5694.715264236772;1035.3787727363522;1264.9114561980573;1302.8371453568304;1315.3226088564397;1323.596303127536;848.5714714069355;871.9476831390136;894.1550235027836;897.3404547657467;903.796645754713;5092;5123.410807757081;5168.793248270103;5170.870728231749;5172.379314482287;5169.844904652867;5680.346760122638;5698.772049118072;5705.343885453226;5727.427257274323;5050.621203409759;5107.591392688576;5135.768890399941;5136.8715782105;5180.974095129575;2751.9036833710725;2896.232498387929;2914.57738627902;2964.5553167024686;2964.9258768530526;5055.469175736568;5109.591232701375;5137.974310128426;5402.810901618424;5499.285092937893;4797.64017698674;5749.913751293734;5844.211031510692;5864.765409383662;5901.996660567714;4841.934774349637;5341.359036915595;5782.59521833476;5898.8820223595285;5937.881242375129;4433.423654924968;5687.83862132387;5717.313922329347;5722.713864306851;5742.368339482737;5614.157876318585;5615.354234263112;5645.943540564706;5651.491365777099;5657.2928383952;5574.442555744393;5766.625169363978;5872.091570239774;5895.469407753323;5898.20374249488;3331.550240717472;3715.702743780545;3782.0922978485514;3830.2339532093138;3833.5016447862713;4475.753833539186;5398.9190162146815;5723.198752174782;5735.7705691773535;5763.827085187451;4759.452662943806;5226.921596175985;5421.484958928895;5493.835184944458;5519.613627046068;4814.662973591815;5004.87487812793;5526.557875370041;5540.252065971043;5556.082582320097;5176.964607078675;5431.4840090191365;5431.511163995101;5433.212184233185;5436.836894893159;4746.976265118652;5380.623356365173;5417.702026388586;5506.394296627448;5512.0983260774965;5106.514881086291;5269.578433725216;5374.274472946102;5400.567954563547;5415.5126038655135 15:35:38 INFO - PID 1640 | |5;yui.html;752.6989204318273;753.4048101999421;757.5341165183411;779.6083990077713;779.9961000194999;13461.865381346195;13560.728785424286;14146.080504767184;15037.248137593118;15849.683006339881;15422.460213892517;15688.921555392208;17122.143892805372;17156.914215428904;17475.300987960465;945.7210123013728;964.3056998960772;969.0067680627343;972.154225823531;974.0016483104839;942.4109931292942;994.4778991029731;1008.991008991009;1009.9950549203515;1012.7063151686002;902.6254104546605;1021.2391768132751;1026.307242611238;1026.4867566216892;1037.3101887245011;1012.3520946594185;1018.4703953943945;1021.7139201023688;1026.0919086608405;1026.14829691356;738.8152961759561;749.2844333661341;752.0974830203777;752.6801109528428;766.5285849202756;15374.231288435534;15374.231288435645;15588.532344029583;15609.765853512206;15611.609709757233;16760.748588771177;17274.740878886827;17295.827041729674;17308.480745577654;17389.91305043467;13201.933990329986;13490.797638035438;13636.65908352299;13662.93168534161;13671.17972921618;3431.2108215110416;3440.2775417162425;3471.2710330830555;3472.131967008248;3474.096734848932;122274.77725222723;136102.31948840193;137481;138948.30525847408;139852;12710.428030738454;13216.933915330554;13256.867431325756;13396;13436.664083397798;25179.87410062938;25304.11435599745;25533.61699574508;25655.615165772528;25990.740092598833;7118.216996130422;7190.892136617955;7198;7209.89185162223;7217.06178196831;3620.837062332201;3669.2478042001326;3716.591174970751;3763.1156678180605;3778.7732736035928;3660.7803531788177;3705.4997575327525;4117.711760176759;4123.340265557556;4170.416141740159;852.1691350932791;878.7056336127341;890.1543533643065;892.9151730585584;896.0009589307875;543.0821910970448;566.7959534567552;571.9142128680649;580.2601682854311;590.9172715819789;10035.648752293635;10269.127124194378;10392.636257730943;11026.228164028442;11694.655114661753;486.9853904382874;492.5050324423931;565.6606036378222;573.9225204597409;592.5378205000106;5142.691438513626;5202.271681964528;5244.475552444801;5381.408045115034;5484.972575137099;94820;126588;128940;130320.57435405145;130924;110538.34192487119;113052.86947130588;113595;114011;114214.85785142209;96137.03862961421;98241;98882.50558747305;98950.01049989408;99226.50386747875;8892.28861691076;10552.261341706007;10597.364158150536;10783.083437907551;10923.781524369628;32031.83984080158;34958.65041349554;41925.742227733215;42556.78721606372;43096.78451607847;59025;59047.40952590419;59425.81148377094;60126.39873601208;60307;13302.733945321233;13347.132436391603;13780.379882905494;13898.444062237397;13921.025528212927;14450.638734031312;15004.399824007354;15222.314995825436;15312.004719693185;15342;13748.968827338189;13908.721825563634;14012.64968375758;14177.36201870939;14299.785503217252;13994.720105598035;14019.369128389042;14164.858351416355;14220.644483887985;14224.288785560888;13872.375743091787;13921.164730116225;14141.646458838612;14168.929155354157;14252.501162459264;10149.441780701836;10171.23715721309;10252.794944101226;10293.639722609672;10509.001644843727;10719.946400267949;10755.139588833032;11070.944645276722;11187.944060279646;11596.072314215011 15:35:38 INFO - PID 1640 | __end_tp_report 15:35:38 INFO - PID 1640 | __start_cc_report 15:35:38 INFO - PID 1640 | _x_x_mozilla_cycle_collect,416 15:35:38 INFO - PID 1640 | __end_cc_report 15:35:38 INFO - PID 1640 | __startTimestamp1495665338735__endTimestamp 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | ------- Summary: start ------- 15:35:38 INFO - PID 1640 | Number of tests: 6 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:5252.78 Median:5463.99 stddev:674.34 (12.3%) stddev-sans-first:652.50 15:35:38 INFO - PID 1640 | Values: 2984.0 2998.8 3030.0 3057.8 3076.4 4978.8 5049.7 5130.5 5170.2 5176.7 4302.7 5056.2 5068.3 5162.4 5179.9 3722.6 3742.4 4576.2 4626.7 4711.5 4556.4 4680.0 4691.3 4707.0 4711.3 4490.4 4588.9 4647.5 4686.0 4736.1 4529.0 4610.9 4649.0 4656.6 4704.4 4103.4 4606.4 4610.1 4619.1 4686.3 4980.4 4996.8 5118.1 5146.2 5148.3 4271.0 5640.4 5662.2 5715.6 5719.8 5371.1 5859.8 5861.5 5884.1 5915.8 5430.6 5434.6 5450.6 5462.0 5464.0 5804.7 5838.1 5849.7 5916.2 5964.2 5541.7 5603.7 5617.7 5716.6 5728.8 5771.4 5773.5 5856.2 5864.7 5895.6 5687.8 5690.4 5780.0 5794.3 5818.8 5025.8 5584.7 5779.8 5796.1 5847.8 4489.8 4594.0 4637.7 4675.3 4712.5 3442.5 4171.4 4178.6 4220.1 4254.9 5259.3 5381.8 5384.5 5393.9 5404.6 5675.2 5749.4 5758.4 5804.1 5880.6 4858.0 5078.6 5218.8 5387.9 5421.2 5108.6 5119.6 5121.1 5121.2 5150.6 5809.6 5868.7 5914.5 5983.3 5993.2 5403.1 5870.3 5873.9 5901.9 5944.3 5934.2 5935.6 5945.3 5949.9 5964.9 5289.0 5540.4 5645.5 5723.8 5761.0 5794.2 5850.3 5878.3 5890.7 5916.0 5624.4 5670.7 5701.1 5715.3 5737.7 5559.6 5576.9 5609.2 5648.1 5698.1 5448.8 5567.7 5580.8 5593.7 5607.9 4670.9 5672.6 5672.8 5716.6 5722.2 5378.5 5491.0 5500.3 5561.1 5592.8 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:42199.98 Median:33369.08 stddev:72947.93 (218.6%) stddev-sans-first:73085.81 15:35:38 INFO - PID 1640 | Values: 632.6 735.4 779.6 1120.4 1186.8 35908.8 36870.4 36901.0 36911.8 36935.4 36116.0 36463.5 36509.8 36696.8 36707.6 7340.8 7447.0 7706.0 7956.9 7963.3 7917.2 7984.6 8010.6 8032.7 8034.3 7982.4 8001.6 8034.1 8051.6 8063.3 7215.2 7647.3 7989.3 8018.7 8061.8 7761.5 7777.2 7845.3 7846.6 7888.4 35172.5 35240.1 35251.5 35307.6 35416.3 3223.8 3232.1 3256.1 3269.3 3270.5 28604.7 28934.7 28938.3 28955.7 29053.0 2978.8 3010.3 3024.4 3026.0 3028.0 241234.0 244893.0 291721.0 292805.0 294451.5 44644.8 45483.5 45498.0 45525.8 45665.0 47219.0 47312.3 47326.1 47460.1 50848.0 13563.0 13657.1 13696.2 13726.7 13759.2 10966.2 10995.4 11063.6 11068.5 11101.7 12760.3 12825.7 12872.2 12872.4 12955.9 5809.9 5838.2 5871.9 5889.9 5897.3 813.6 866.7 885.9 900.0 910.2 16334.3 18260.9 20959.9 22500.3 22784.8 994.9 1013.6 1035.2 1038.1 1043.7 9922.9 11185.2 11787.0 12012.5 12043.0 289729.0 291977.0 292076.0 292649.0 294067.0 46849.8 51003.5 51226.7 51470.0 51471.5 1257.3 1389.6 1402.6 1423.9 1428.3 22207.9 22709.8 23039.1 23183.7 23227.8 35881.8 35895.0 35900.8 35952.0 35983.0 267167.0 267802.0 267918.7 269465.0 270161.0 33340.3 33397.8 33416.5 33492.3 33663.7 40567.4 41240.6 41379.4 42207.9 43022.0 28277.3 28823.6 29260.7 29835.4 30138.0 24153.8 28170.0 28959.4 30150.0 30179.2 34126.3 34675.8 35356.8 36054.0 36123.6 29447.4 29657.7 29953.3 30084.2 30123.8 22300.9 22657.4 23115.4 23404.5 23505.5 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:33685.64 Median:2244.01 stddev:90317.97 (4024.8%) stddev-sans-first:90536.66 15:35:38 INFO - PID 1640 | Values: 352.9 365.9 398.9 404.3 415.1 18496.0 19534.5 19852.6 19956.2 20008.3 20836.6 21072.4 21132.7 21269.6 21325.1 5308.0 5508.3 5763.6 5774.3 5798.0 4382.0 5664.7 5665.2 5667.4 5677.6 5588.2 5596.0 5630.7 5645.0 5649.9 5491.7 5575.8 5628.6 5630.6 5633.7 4975.2 5001.9 5025.6 5027.1 5051.9 23811.0 24038.9 24131.6 24151.5 24390.3 2074.8 2119.2 2121.9 2133.9 2146.2 16576.8 16680.4 16865.8 17102.6 17158.1 1991.1 2020.2 2054.9 2065.7 2068.5 137700.0 138877.0 140698.3 141016.0 142314.3 11468.7 11616.7 11659.9 11775.8 11989.0 14798.9 15315.4 15376.5 15377.0 15455.8 8484.2 8484.5 8586.2 8612.4 8727.5 8199.6 8225.3 8244.6 8395.5 8400.2 6109.2 7275.3 7365.9 7424.2 7481.4 1835.6 1923.5 2165.2 2171.8 2316.3 665.5 756.2 760.9 788.2 818.8 18654.5 19638.6 19685.9 19804.0 20355.4 891.8 910.6 914.6 933.6 933.8 8294.0 8578.3 10058.1 10090.4 10101.6 528717.0 531969.3 532051.0 535881.0 546703.0 116858.4 118632.4 120903.0 121412.4 123913.0 1035.3 1068.8 1085.1 1087.1 1094.5 16921.4 17115.4 17168.9 17444.6 17635.5 44267.8 54706.7 54939.7 55295.7 55634.4 73246.6 74162.6 74164.0 74841.0 77986.0 7601.1 8235.9 8312.5 8360.7 8723.4 11234.9 11328.8 11389.0 13160.3 13550.2 11037.8 11219.4 11469.5 11501.5 11545.4 8532.5 10441.5 10771.8 10826.6 10915.2 11542.2 11617.4 11646.3 11662.8 11713.5 8268.5 8320.5 8470.2 8545.3 8561.7 11437.5 11531.0 11622.5 11646.7 11740.2 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6954.08 Median:3737.72 stddev:4505.63 (120.5%) stddev-sans-first:4497.08 15:35:38 INFO - PID 1640 | Values: 1139.8 1353.5 1366.5 1374.1 1376.8 11123.2 11253.8 11817.2 11848.6 11912.6 11656.8 11663.6 11698.2 11843.2 11856.9 2411.4 2433.8 2440.3 2453.5 2457.2 2392.9 2409.2 2433.5 2436.2 2451.0 2390.1 2415.0 2427.7 2429.1 2430.1 2303.5 2379.5 2392.8 2393.9 2405.4 2012.9 2101.8 2344.5 2366.8 2378.7 11430.7 11498.2 11571.9 11583.7 11600.2 4897.7 6351.6 6364.2 6397.9 6405.5 3808.2 4026.3 4029.9 4079.6 4082.0 1179.5 1185.3 1211.1 1218.1 1240.9 16161.4 16267.1 16442.9 16448.2 16455.6 5264.6 5271.9 5280.2 5403.0 5428.5 11422.3 11529.5 11581.8 11592.8 11608.4 5938.8 6094.8 6117.8 6167.1 6182.7 5709.7 5848.2 5874.8 5903.2 5910.6 4217.3 4294.0 4309.7 4321.4 4330.8 1070.6 1230.5 1235.9 1252.3 1253.3 644.8 663.0 694.1 697.3 707.4 5748.6 5937.4 6005.6 6042.3 6996.7 831.6 834.4 842.9 844.3 854.9 3728.8 3746.6 4194.9 4494.4 4765.0 14633.6 14637.8 14931.3 14969.6 14982.3 14240.4 14663.1 15039.1 15041.2 15043.9 7996.4 8181.5 8246.8 8300.6 8357.2 5962.4 6557.7 6733.9 6776.2 6799.8 13091.9 13414.6 13527.3 13543.3 13565.4 13677.0 13724.4 13749.7 13755.2 13888.2 8754.3 8943.6 9074.6 9132.4 9181.7 10542.7 10616.5 10701.4 10708.4 10734.6 8737.2 8871.5 8953.7 8999.9 9011.9 8508.1 8606.3 8683.5 8710.4 8727.8 7872.8 7942.9 7971.3 8007.7 8017.7 5571.1 5690.4 5717.2 5769.8 5901.1 5776.6 5863.6 5883.5 5904.2 5978.0 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:4401.42 Median:5003.67 stddev:1378.52 (27.6%) stddev-sans-first:1347.70 15:35:38 INFO - PID 1640 | Values: 306.9 363.2 370.1 373.4 380.9 4179.8 4214.5 4901.5 5127.8 5182.4 4003.5 4995.7 4996.0 5002.5 5083.4 3601.0 3604.7 3744.4 3775.8 3784.9 2957.3 3732.8 3787.2 3812.0 3828.7 3722.9 3754.6 3758.4 3759.8 3769.2 3374.7 3537.6 3717.2 3786.7 3794.3 2705.5 3098.2 3261.2 3278.8 3297.6 4792.3 4795.2 4827.0 4837.7 4854.7 3683.7 4422.2 4469.7 4501.7 4514.3 5339.3 5398.1 5411.4 5443.0 5467.5 3770.4 3773.0 3775.3 3786.1 3791.2 4142.4 4664.0 5518.2 5683.7 5720.6 3180.6 3666.8 3763.9 3782.9 3800.3 3481.2 3834.3 3863.5 3873.1 3881.6 4080.9 4469.9 4477.7 4480.8 4485.7 5308.8 5434.6 5443.5 5513.9 5694.7 1035.4 1264.9 1302.8 1315.3 1323.6 848.6 871.9 894.2 897.3 903.8 5092.0 5123.4 5168.8 5170.9 5172.4 5169.8 5680.3 5698.8 5705.3 5727.4 5050.6 5107.6 5135.8 5136.9 5181.0 2751.9 2896.2 2914.6 2964.6 2964.9 5055.5 5109.6 5138.0 5402.8 5499.3 4797.6 5749.9 5844.2 5864.8 5902.0 4841.9 5341.4 5782.6 5898.9 5937.9 4433.4 5687.8 5717.3 5722.7 5742.4 5614.2 5615.4 5645.9 5651.5 5657.3 5574.4 5766.6 5872.1 5895.5 5898.2 3331.6 3715.7 3782.1 3830.2 3833.5 4475.8 5398.9 5723.2 5735.8 5763.8 4759.5 5226.9 5421.5 5493.8 5519.6 4814.7 5004.9 5526.6 5540.3 5556.1 5177.0 5431.5 5431.5 5433.2 5436.8 4747.0 5380.6 5417.7 5506.4 5512.1 5106.5 5269.6 5374.3 5400.6 5415.5 15:35:38 INFO - PID 1640 | 15:35:38 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:22986.18 Median:15769.30 stddev:35663.30 (226.2%) stddev-sans-first:35724.27 15:35:38 INFO - PID 1640 | Values: 752.7 753.4 757.5 779.6 780.0 13461.9 13560.7 14146.1 15037.2 15849.7 15422.5 15688.9 17122.1 17156.9 17475.3 945.7 964.3 969.0 972.2 974.0 942.4 994.5 1009.0 1010.0 1012.7 902.6 1021.2 1026.3 1026.5 1037.3 1012.4 1018.5 1021.7 1026.1 1026.1 738.8 749.3 752.1 752.7 766.5 15374.2 15374.2 15588.5 15609.8 15611.6 16760.7 17274.7 17295.8 17308.5 17389.9 13201.9 13490.8 13636.7 13662.9 13671.2 3431.2 3440.3 3471.3 3472.1 3474.1 122274.8 136102.3 137481.0 138948.3 139852.0 12710.4 13216.9 13256.9 13396.0 13436.7 25179.9 25304.1 25533.6 25655.6 25990.7 7118.2 7190.9 7198.0 7209.9 7217.1 3620.8 3669.2 3716.6 3763.1 3778.8 3660.8 3705.5 4117.7 4123.3 4170.4 852.2 878.7 890.2 892.9 896.0 543.1 566.8 571.9 580.3 590.9 10035.6 10269.1 10392.6 11026.2 11694.7 487.0 492.5 565.7 573.9 592.5 5142.7 5202.3 5244.5 5381.4 5485.0 94820.0 126588.0 128940.0 130320.6 130924.0 110538.3 113052.9 113595.0 114011.0 114214.9 96137.0 98241.0 98882.5 98950.0 99226.5 8892.3 10552.3 10597.4 10783.1 10923.8 32031.8 34958.7 41925.7 42556.8 43096.8 59025.0 59047.4 59425.8 60126.4 60307.0 13302.7 13347.1 13780.4 13898.4 13921.0 14450.6 15004.4 15222.3 15312.0 15342.0 13749.0 13908.7 14012.6 14177.4 14299.8 13994.7 14019.4 14164.9 14220.6 14224.3 13872.4 13921.2 14141.6 14168.9 14252.5 10149.4 10171.2 10252.8 10293.6 10509.0 10719.9 10755.1 11070.9 11187.9 11596.1 15:35:38 INFO - PID 1640 | -------- Summary: end -------- 15:35:38 INFO - PID 1640 | 15:35:39 INFO - TEST-INFO | 1640: exit 0 15:35:39 INFO - TEST-OK | dromaeo_css | took 1141384ms 15:35:39 INFO - TEST-START | kraken 15:35:39 INFO - operating with platform_type : mac_ 15:35:39 INFO - Initialising browser for kraken test... 15:35:39 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp0ANlbN/profile 15:35:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 15:36:12 INFO - TEST-INFO | 1652: killed by SIGTERM 15:36:12 INFO - Browser initialized. 15:36:12 INFO - Running cycle 1/1 for kraken test... 15:36:12 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp0ANlbN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:15 INFO - PID 1656 | 2017-05-24 15:36:15.142 plugin-container[1657:10909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 15:36:15 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:15 INFO - PID 1656 | 2017-05-24 15:36:15.143 plugin-container[1657:10909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 15:36:15 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:15 INFO - PID 1656 | 2017-05-24 15:36:15.143 plugin-container[1657:10909] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:36:15 INFO - PID 1656 | 2017-05-24 15:36:15.520 plugin-container[1658:10951] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 15:36:15 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:15 INFO - PID 1656 | 2017-05-24 15:36:15.521 plugin-container[1658:10951] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9917, name = 'com.apple.CFPasteboardClient' 15:36:15 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:15 INFO - PID 1656 | 2017-05-24 15:36:15.521 plugin-container[1658:10951] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:36:24 INFO - PID 1656 | RSS: Main: 246411264 15:36:24 INFO - PID 1656 | 15:36:27 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection) 15:36:28 INFO - PID 1656 | RSS: Main: 260096000 15:36:28 INFO - PID 1656 | 15:36:31 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-dft) 15:36:32 INFO - PID 1656 | RSS: Main: 269107200 15:36:32 INFO - PID 1656 | 15:36:36 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-fft) 15:36:36 INFO - PID 1656 | RSS: Main: 272400384 15:36:36 INFO - PID 1656 | 15:36:38 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator) 15:36:39 INFO - PID 1656 | RSS: Main: 273010688 15:36:39 INFO - PID 1656 | 15:36:41 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:36:41 INFO - PID 1656 | RSS: Main: 274677760 15:36:41 INFO - PID 1656 | 15:36:45 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom) 15:36:45 INFO - PID 1656 | RSS: Main: 279343104 15:36:45 INFO - PID 1656 | 15:36:48 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate) 15:36:49 INFO - PID 1656 | RSS: Main: 279437312 15:36:49 INFO - PID 1656 | 15:36:52 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial) 15:36:53 INFO - PID 1656 | RSS: Main: 279416832 15:36:53 INFO - PID 1656 | 15:36:54 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:36:55 INFO - PID 1656 | RSS: Main: 279298048 15:36:55 INFO - PID 1656 | 15:36:57 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:36:57 INFO - PID 1656 | RSS: Main: 279166976 15:36:57 INFO - PID 1656 | 15:37:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:37:00 INFO - PID 1656 | RSS: Main: 279187456 15:37:00 INFO - PID 1656 | 15:37:03 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:37:04 INFO - PID 1656 | RSS: Main: 279650304 15:37:04 INFO - PID 1656 | 15:37:07 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:37:08 INFO - PID 1656 | RSS: Main: 279433216 15:37:08 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49232/tests/kraken/driver.html?testName=ai-astar) 15:37:10 INFO - PID 1656 | RSS: Main: 282324992 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | __start_tp_report 15:37:10 INFO - PID 1656 | _x_x_mozilla_page_load 15:37:10 INFO - PID 1656 | _x_x_mozilla_page_load_details 15:37:10 INFO - PID 1656 | |i|pagename|runs| 15:37:10 INFO - PID 1656 | |0;ai-astar;107;120;119;117;142;176;153;118;119;145 15:37:10 INFO - PID 1656 | |1;audio-beat-detection;151;116;116;155;117;117;143;124;162;145 15:37:10 INFO - PID 1656 | |2;audio-dft;230;231;248;252;262;250;314;251;239;317 15:37:10 INFO - PID 1656 | |3;audio-fft;86;62;64;88;70;78;62;59;77;58 15:37:10 INFO - PID 1656 | |4;audio-oscillator;87;70;84;75;71;71;94;86;102;94 15:37:10 INFO - PID 1656 | |5;imaging-gaussian-blur;117;94;128;88;129;137;91;99;104;92 15:37:10 INFO - PID 1656 | |6;imaging-darkroom;99;98;98;98;98;98;100;98;99;97 15:37:10 INFO - PID 1656 | |7;imaging-desaturate;106;108;108;108;132;109;113;106;108;139 15:37:10 INFO - PID 1656 | |8;json-parse-financial;52;49;49;53;51;52;53;55;53;51 15:37:10 INFO - PID 1656 | |9;json-stringify-tinderbox;60;67;59;62;65;62;59;69;68;62 15:37:10 INFO - PID 1656 | |10;stanford-crypto-aes;88;96;93;92;92;92;104;92;141;91 15:37:10 INFO - PID 1656 | |11;stanford-crypto-ccm;144;150;173;151;161;171;170;155;157;149 15:37:10 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;200;211;217;203;247;244;199;197;198;232 15:37:10 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;71;69;73;72;75;97;92;97;101;98 15:37:10 INFO - PID 1656 | __end_tp_report 15:37:10 INFO - PID 1656 | __start_cc_report 15:37:10 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1099 15:37:10 INFO - PID 1656 | __end_cc_report 15:37:10 INFO - PID 1656 | __startTimestamp1495665430764__endTimestamp 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | ------- Summary: start ------- 15:37:10 INFO - PID 1656 | Number of tests: 14 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:131.60 Median:131.00 stddev:21.53 (16.4%) stddev-sans-first:20.92 15:37:10 INFO - PID 1656 | Values: 107.0 120.0 119.0 117.0 142.0 176.0 153.0 118.0 119.0 145.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:144.00 stddev:18.37 (12.8%) stddev-sans-first:18.51 15:37:10 INFO - PID 1656 | Values: 151.0 116.0 116.0 155.0 117.0 117.0 143.0 124.0 162.0 145.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:259.40 Median:251.50 stddev:31.16 (12.4%) stddev-sans-first:31.18 15:37:10 INFO - PID 1656 | Values: 230.0 231.0 248.0 252.0 262.0 250.0 314.0 251.0 239.0 317.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:70.40 Median:73.50 stddev:11.16 (15.2%) stddev-sans-first:10.31 15:37:10 INFO - PID 1656 | Values: 86.0 62.0 64.0 88.0 70.0 78.0 62.0 59.0 77.0 58.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:85.00 stddev:11.30 (13.3%) stddev-sans-first:11.91 15:37:10 INFO - PID 1656 | Values: 87.0 70.0 84.0 75.0 71.0 71.0 94.0 86.0 102.0 94.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:107.90 Median:89.50 stddev:18.26 (20.4%) stddev-sans-first:19.07 15:37:10 INFO - PID 1656 | Values: 117.0 94.0 128.0 88.0 129.0 137.0 91.0 99.0 104.0 92.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:98.00 stddev:0.82 (0.8%) stddev-sans-first:0.83 15:37:10 INFO - PID 1656 | Values: 99.0 98.0 98.0 98.0 98.0 98.0 100.0 98.0 99.0 97.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:113.70 Median:108.50 stddev:11.77 (10.8%) stddev-sans-first:12.15 15:37:10 INFO - PID 1656 | Values: 106.0 108.0 108.0 108.0 132.0 109.0 113.0 106.0 108.0 139.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.50 stddev:1.87 (3.6%) stddev-sans-first:1.99 15:37:10 INFO - PID 1656 | Values: 52.0 49.0 49.0 53.0 51.0 52.0 53.0 55.0 53.0 51.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.50 stddev:3.71 (5.8%) stddev-sans-first:3.74 15:37:10 INFO - PID 1656 | Values: 60.0 67.0 59.0 62.0 65.0 62.0 59.0 69.0 68.0 62.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:98.10 Median:92.00 stddev:15.66 (17.0%) stddev-sans-first:16.18 15:37:10 INFO - PID 1656 | Values: 88.0 96.0 93.0 92.0 92.0 92.0 104.0 92.0 141.0 91.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:159.00 stddev:10.26 (6.5%) stddev-sans-first:9.53 15:37:10 INFO - PID 1656 | Values: 144.0 150.0 173.0 151.0 161.0 171.0 170.0 155.0 157.0 149.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:214.80 Median:214.00 stddev:19.47 (9.1%) stddev-sans-first:19.90 15:37:10 INFO - PID 1656 | Values: 200.0 211.0 217.0 203.0 247.0 244.0 199.0 197.0 198.0 232.0 15:37:10 INFO - PID 1656 | 15:37:10 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.50 Median:83.50 stddev:13.44 (16.1%) stddev-sans-first:13.33 15:37:10 INFO - PID 1656 | Values: 71.0 69.0 73.0 72.0 75.0 97.0 92.0 97.0 101.0 98.0 15:37:10 INFO - PID 1656 | -------- Summary: end -------- 15:37:10 INFO - PID 1656 | 15:37:11 INFO - TEST-INFO | 1656: exit 0 15:37:11 INFO - TEST-OK | kraken | took 92268ms 15:37:11 INFO - SUITE-END | took 1233s 15:37:11 INFO - Completed test suite (00:20:33) 15:37:11 INFO - javascript benchmark 15:37:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7173.655612389924, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2983.9705301670924, 2998.7900846940724, 3030.045535656268, 3057.831819249942, 3076.369344284421, 4978.800847966077, 5049.671771334865, 5130.461301563333, 5170.198619214018, 5176.689398636085, 4302.677299202556, 5056.165732654108, 5068.315777370054, 5162.354705661793, 5179.922301165476, 3722.553293604771, 3742.4386342048774, 4576.199165146086, 4626.7455289959125, 4711.528847115278, 4556.3621093047, 4680.017196388762, 4691.272852707836, 4707.0, 4711.316859055452, 4490.39379683745, 4588.885277868047, 4647.535246475325, 4685.969086800898, 4736.052789442108, 4529.0, 4610.9077818443775, 4648.953510464886, 4656.627469802442, 4704.435467743892, 4103.384492326145, 4606.355110284563, 4610.101030299093, 4619.12236675034, 4686.297055441711, 4980.402351717781, 4996.7501624918605, 5118.07874582575, 5146.176611742141, 5148.304978827885, 4271.0, 5640.435956404368, 5662.17898404729, 5715.571332150105, 5719.799807006776, 5371.113766228567, 5859.794907178229, 5861.472467477947, 5884.117382392676, 5915.822525324204, 5430.5927055471, 5434.64674796137, 5450.591205659592, 5462.0441422751255, 5463.989162004962, 5804.680742559115, 5838.095095260242, 5849.707514624251, 5916.230889984275, 5964.224650795456, 5541.722913854372, 5603.663780173202, 5617.662940223599, 5716.57125715573, 5728.796952639909, 5771.422857714195, 5773.509251713652, 5856.180134781134, 5864.736086876101, 5895.55783316253, 5687.800926967537, 5690.430956904359, 5780.0, 5794.30468343793, 5818.8254352369495, 5025.84922452327, 5584.692841893735, 5779.768809247667, 5796.101604251347, 5847.795327163529, 4489.820407183676, 4593.977030114895, 4637.721736695809, 4675.345451636774, 4712.528747125329, 3442.4836274558515, 4171.374293855885, 4178.561251068655, 4220.092680073799, 4254.87235382939, 5259.266660006026, 5381.838544843661, 5384.461553844584, 5393.919091213635, 5404.621676482609, 5675.2054712340305, 5749.425057494217, 5758.395368486333, 5804.1293805929445, 5880.617759845596, 4858.0, 5078.603384069336, 5218.765155568009, 5387.946120538744, 5421.213923981, 5108.56577190943, 5119.6160287978555, 5121.052605268037, 5121.180611102206, 5150.587952963829, 5809.622374545641, 5868.735906884286, 5914.526837852962, 5983.34183239852, 5993.220881285405, 5403.13549832025, 5870.295564532284, 5873.882522349614, 5901.940980590139, 5944.346121926671, 5934.169216309634, 5935.5548333875995, 5945.256842894638, 5949.940500595112, 5964.940350596612, 5288.9735551322, 5540.390557038803, 5645.463680950304, 5723.82661554372, 5761.0, 5794.246747922912, 5850.268716410449, 5878.3239927407185, 5890.7054647266605, 5915.970420147872, 5624.437556244343, 5670.74481648335, 5701.059325211294, 5715.342735585461, 5737.74180161902, 5559.610827242216, 5576.86057848557, 5609.21470994053, 5648.068068768607, 5698.1452782083015, 5448.809291674773, 5567.721613919369, 5580.77676892936, 5593.692346920959, 5607.859803504782, 4670.859874203779, 5672.631278966854, 5672.829815105553, 5716.628419152742, 5722.227499287543, 5378.489043540857, 5491.0, 5500.28496295479, 5561.110222364564, 5592.832215033555], "value": 5206.654408836881, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.5572099530328, 735.4263674334021, 779.5751315533032, 1120.3893877836579, 1186.8397766301546, 35908.820455897716, 36870.44694329587, 36901.0, 36911.81544092276, 36935.445968310494, 36116.0, 36463.45304820423, 36509.817450912706, 36696.81651591745, 36707.63292367075, 7340.816479588013, 7447.0, 7706.0, 7956.880646790303, 7963.3231175350165, 7917.247861453153, 7984.6007699615075, 8010.5594192319395, 8032.718854840082, 8034.317083047948, 7982.3614110871285, 8001.639926203335, 8034.116247212802, 8051.59742012897, 8063.314618257456, 7215.242399548024, 7647.311741943252, 7989.280964713174, 8018.67925282988, 8061.798455038671, 7761.45669803114, 7777.183395743478, 7845.333146682538, 7846.607669616496, 7888.40836937232, 35172.47241291383, 35240.11899702502, 35251.4712279316, 35307.646923530694, 35416.29167416637, 3223.774335796495, 3232.1273256220925, 3256.1208473711963, 3269.29710112327, 3270.4930735735998, 28604.713952860413, 28934.710652893624, 28938.27654308638, 28955.71044289572, 29053.0, 2978.8063775854716, 3010.32267739759, 3024.364883374472, 3025.9546106808416, 3028.0, 241234.0, 244893.0, 291721.0, 292805.0, 294451.5277423599, 44644.77677611591, 45483.545164548595, 45498.0, 45525.77237113793, 45665.0, 47219.0, 47312.290315645296, 47326.053478930226, 47460.05079898452, 50848.0, 13563.0, 13657.112287701466, 13696.178229306273, 13726.65683357924, 13759.243241621807, 10966.232363734622, 10995.395253260987, 11063.557457701601, 11068.505751723575, 11101.666949991513, 12760.277362555937, 12825.679358016125, 12872.16330938486, 12872.356382180853, 12955.935220323838, 5809.912851307234, 5838.241028666331, 5871.941280587225, 5889.852753681193, 5897.292324921037, 813.6419975210888, 866.6793286484042, 885.8538341173763, 899.9550022498848, 910.1717437132311, 16334.264958076916, 18260.904345739298, 20959.8952005239, 22500.32499025032, 22784.772152278598, 994.8806143262855, 1013.5844303835393, 1035.2028937717914, 1038.1295717341557, 1043.6973277749519, 9922.858871229757, 11185.217034807485, 11786.998105160987, 12012.519499220109, 12043.0, 289729.0, 291977.0, 292076.0, 292649.0, 294067.0, 46849.76575117103, 51003.48996510061, 51226.74386628118, 51470.0, 51471.48528514667, 1257.270782945889, 1389.6109089454767, 1402.6493376655835, 1423.8576142385887, 1428.2715814934459, 22207.888960555094, 22709.772902270764, 23039.07843686234, 23183.652245216, 23227.767722322562, 35881.82059089688, 35895.0, 35900.82049589735, 35952.0, 35983.0, 267167.0, 267802.0, 267918.6604066967, 269465.0, 270161.0, 33340.33319333648, 33397.83301083479, 33416.49875251922, 33492.32061435128, 33663.663363367035, 40567.3914891271, 41240.58759412367, 41379.379309309785, 42207.94480138021, 43022.0, 28277.29306767265, 28823.56764648574, 29260.707392925797, 29835.403291933606, 30138.0, 24153.75846241515, 28170.0, 28959.42081158323, 30150.0, 30179.245518862204, 34126.31747365089, 34675.826620867745, 35356.823215883756, 36054.0, 36123.63876361203, 29447.411051779272, 29657.665405057254, 29953.313539073424, 30084.24789380283, 30123.849380752956, 22300.88849555807, 22657.433564161027, 23115.422114447272, 23404.531909362056, 23505.529889401772], "value": 16468.022518001824, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.9276498317842, 365.941449368101, 398.8723608445297, 404.2844165826487, 415.10751883450575, 18496.0, 19534.51163720904, 19852.60294794105, 19956.201751929904, 20008.29970951017, 20836.583268334623, 21072.367828965154, 21132.683009754866, 21269.57460850782, 21325.146994120256, 5307.964946835368, 5508.338999320077, 5763.625364351325, 5774.2782152230975, 5797.971010144944, 4381.9561804381865, 5664.745086471119, 5665.206871038037, 5667.433256674341, 5677.602567820254, 5588.189712491686, 5595.972020139893, 5630.7184640768, 5645.040343141676, 5649.943500565003, 5491.725413729318, 5575.832725018256, 5628.592851787053, 5630.60585758995, 5633.746481408305, 4975.233792003859, 5001.924971125436, 5025.623078269108, 5027.069992051483, 5051.924221136685, 23811.047558097827, 24038.879805601035, 24131.63802542963, 24151.516969660508, 24390.26829195127, 2074.7614024387085, 2119.237074653139, 2121.917821910821, 2133.8506304558687, 2146.1844499090244, 16576.75134872978, 16680.416185433452, 16865.83134168655, 17102.572435689082, 17158.142092895305, 1991.1139542903413, 2020.1818263603263, 2054.897255137237, 2065.6901464780253, 2068.4621998280404, 137700.0, 138877.0, 140698.29650851781, 141016.0, 142314.28842855824, 11468.713282168013, 11616.651500455, 11659.941700291614, 11775.823362649568, 11989.0, 14798.926005369904, 15315.387384504495, 15376.461823836113, 15377.001484710598, 15455.768163477556, 8484.236418722345, 8484.490930544187, 8586.22723954847, 8612.397132200811, 8727.519986400683, 8199.590020498952, 8225.341972642174, 8244.628991695388, 8395.454295470774, 8400.243978042006, 6109.17964003543, 7275.345218930322, 7365.926340736632, 7424.215919030364, 7481.428899931076, 1835.6145209506153, 1923.4806602217323, 2165.155589320166, 2171.7502487214174, 2316.2703748319227, 665.4776000839317, 756.228646780281, 760.9308920966051, 788.239349028179, 818.771842236645, 18654.53363665892, 19638.60722785565, 19685.901570492057, 19804.009799509968, 20355.389338319874, 891.7636826241052, 910.6266430763355, 914.6067191107754, 933.6265493802533, 933.8459154239611, 8293.95853020731, 8578.270846978077, 10058.094771470604, 10090.394576325443, 10101.595936162472, 528717.0, 531969.3401532967, 532051.0, 535881.0, 546703.0, 116858.41570792091, 118632.40683796698, 120903.0, 121412.39293803652, 123913.0, 1035.2753072849036, 1068.8396740488988, 1085.1156307609274, 1087.124864484098, 1094.485591771882, 16921.407750728664, 17115.400960966308, 17168.914155429644, 17444.563885902953, 17635.470935871945, 44267.778661107775, 54706.726466367414, 54939.72530137324, 55295.72352138213, 55634.44365556293, 73246.63376683083, 74162.62918685372, 74164.0, 74841.0, 77986.0, 7601.0878694557005, 8235.876461852957, 8312.5428101455, 8360.749177524684, 8723.389362744801, 11234.943825280821, 11328.830067549157, 11389.0, 13160.276184809927, 13550.186988780704, 11037.83443248368, 11219.439028048728, 11469.483873225892, 11501.53993840237, 11545.422728863356, 8532.488050716976, 10441.477926103817, 10771.838422423512, 10826.621068262888, 10915.23593348458, 11542.249753765978, 11617.361045142598, 11646.301221926711, 11662.76674466489, 11713.531458741896, 8268.462549934235, 8320.459170153921, 8470.15298470148, 8545.273651739672, 8561.743147705578, 11437.5431640558, 11531.0, 11622.476988535705, 11646.708832278922, 11740.178187526792], "value": 9663.692375395762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1139.8062329404002, 1353.4586165533792, 1366.5490388171897, 1374.0656353679494, 1376.841663208731, 11123.221374503788, 11253.774924501506, 11817.23187992781, 11848.644540663772, 11912.642620721392, 11656.825147622792, 11663.591774287901, 11698.181127321091, 11843.230190037642, 11856.940715296432, 2411.4205195596824, 2433.8052955763583, 2440.3411079008656, 2453.460238747473, 2457.1522824625563, 2392.880355982194, 2409.2410890569504, 2433.5376278507115, 2436.244764123119, 2450.963235551459, 2390.103711108334, 2415.04605680756, 2427.672264244322, 2429.0526694589125, 2430.1130087518104, 2303.4586872085047, 2379.4646204604, 2392.7966122879575, 2393.940151496209, 2405.4226985523524, 2012.8590998630102, 2101.7898210179014, 2344.4959333743154, 2366.7514910934447, 2378.714554253501, 11430.71423214418, 11498.19512634116, 11571.94214028933, 11583.710407239887, 11600.187986840925, 4897.730624815634, 6351.587146835441, 6364.236291644986, 6397.872042559169, 6405.51958603102, 3808.1622043150464, 4026.315526360526, 4029.8589549365925, 4079.6124368185274, 4082.0, 1179.5281887245171, 1185.2829038431796, 1211.0674780419029, 1218.0925210717983, 1240.8883200511907, 16161.353545858152, 16267.10530920799, 16442.917785411115, 16448.177591120395, 16455.588610284838, 5264.5525130363585, 5271.868203294872, 5280.234366016983, 5402.972985135049, 5428.511433970962, 11422.25755325901, 11529.538818447169, 11581.76836463266, 11592.76814463706, 11608.361540115206, 5938.762449502058, 6094.8171554853425, 6117.816465506042, 6167.074938759132, 6182.6599537025895, 5709.65742055478, 5848.239728835224, 5874.765009399662, 5903.173555702205, 5910.645361278337, 4217.34631132175, 4293.978530107392, 4309.741415515079, 4321.351797230378, 4330.848420305274, 1070.6038765656756, 1230.4954968462887, 1235.8640549539543, 1252.3425201769144, 1253.3106791264768, 644.8162273752052, 662.9801105966828, 694.1323345817727, 697.3444961735952, 707.4199156691463, 5748.597598168171, 5937.376575459601, 6005.609635373687, 6042.335343112254, 6996.6851491683, 831.580052073711, 834.3867257565685, 842.8988521377352, 844.2612713875359, 854.9180205359589, 3728.844058341923, 3746.5878753337656, 4194.937075943863, 4494.415725955605, 4764.95235047652, 14633.560993170222, 14637.780433293508, 14931.328090235966, 14969.550913472613, 14982.325795339018, 14240.359183836752, 14663.120212787268, 15039.097654140787, 15041.247937603295, 15043.924780376028, 7996.440249182618, 8181.468204566684, 8246.793830154174, 8300.584970751439, 8357.247847693738, 5962.37395073511, 6557.704903279459, 6733.932660673527, 6776.186857577122, 6799.796006119825, 13091.869081309065, 13414.597562073153, 13527.256000920044, 13543.255120968048, 13565.38955746974, 13677.0, 13724.382402792098, 13749.656258593615, 13755.243461609707, 13888.236147012012, 8754.29965602763, 8943.642254310014, 9074.591643376196, 9132.40639358466, 9181.724548263563, 10542.736431589272, 10616.469176540988, 10701.357918524913, 10708.3574985498, 10734.624288149877, 8737.169968852948, 8871.467711937305, 8953.686620968236, 8999.865002024844, 9011.864822027806, 8508.106648801786, 8606.311495080254, 8683.522406267715, 8710.390272681105, 8727.78180545466, 7872.763817085496, 7942.920570794449, 7971.322437592869, 8007.719729809429, 8017.719379821679, 5571.1086226203615, 5690.430956904276, 5717.17101020358, 5769.769209231584, 5901.1443340714495, 5776.624519406225, 5863.589548731547, 5883.47048765596, 5904.232449781501, 5978.0], "value": 5190.288201188752, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.8542442339889, 363.2136424640653, 370.08773373634006, 373.36155174651344, 380.8952538052037, 4179.770112643803, 4214.452121224244, 4901.509849015097, 5127.846164615063, 5182.352205974254, 4003.539592946812, 4995.675281106725, 4995.950040499594, 5002.474740152286, 5083.440821509631, 3600.9557228403864, 3604.729645276602, 3744.4383342498572, 3775.7734535927934, 3784.9053773655605, 2957.275467510463, 3732.757370770905, 3787.242551489713, 3812.0, 3828.674562662177, 3722.9069273268115, 3754.6057663945303, 3758.398656215006, 3759.774413535197, 3769.208466222097, 3374.667006532406, 3537.557805274341, 3717.237966216919, 3786.7159963002746, 3794.260119276742, 2705.47243287559, 3098.2099564611103, 3261.2010057536054, 3278.8196649184283, 3297.6207736110414, 4792.281157826319, 4795.20879054959, 4827.0, 4837.685550439211, 4854.732989685566, 3683.686886614641, 4422.248217802949, 4469.664775141878, 4501.729896206205, 4514.255147900592, 5339.332583427072, 5398.109311963522, 5411.431799661018, 5443.0, 5467.535259502947, 3770.4344348347695, 3773.0, 3775.3388509055926, 3786.1291902862495, 3791.165943492455, 4142.420061191436, 4664.017792438201, 5518.19986102017, 5683.744231509633, 5720.6281591696825, 3180.634227063871, 3666.7799932004164, 3763.8870833875026, 3782.865140457852, 3800.3349413852466, 3481.1819222482695, 3834.328992426314, 3863.459115723801, 3873.1479074603544, 3881.5536213335827, 4080.8775736727944, 4469.910601787946, 4477.70894891831, 4480.843170489083, 4485.686001979895, 5308.787648494017, 5434.619576629677, 5443.455654434525, 5513.9448605513435, 5694.715264236772, 1035.3787727363522, 1264.9114561980573, 1302.8371453568304, 1315.3226088564397, 1323.596303127536, 848.5714714069355, 871.9476831390136, 894.1550235027836, 897.3404547657467, 903.796645754713, 5092.0, 5123.410807757081, 5168.793248270103, 5170.870728231749, 5172.379314482287, 5169.844904652867, 5680.346760122638, 5698.772049118072, 5705.343885453226, 5727.427257274323, 5050.621203409759, 5107.591392688576, 5135.768890399941, 5136.8715782105, 5180.974095129575, 2751.9036833710725, 2896.232498387929, 2914.57738627902, 2964.5553167024686, 2964.9258768530526, 5055.469175736568, 5109.591232701375, 5137.974310128426, 5402.810901618424, 5499.285092937893, 4797.64017698674, 5749.913751293734, 5844.211031510692, 5864.765409383662, 5901.996660567714, 4841.934774349637, 5341.359036915595, 5782.59521833476, 5898.8820223595285, 5937.881242375129, 4433.423654924968, 5687.83862132387, 5717.313922329347, 5722.713864306851, 5742.368339482737, 5614.157876318585, 5615.354234263112, 5645.943540564706, 5651.491365777099, 5657.2928383952, 5574.442555744393, 5766.625169363978, 5872.091570239774, 5895.469407753323, 5898.20374249488, 3331.550240717472, 3715.702743780545, 3782.0922978485514, 3830.2339532093138, 3833.5016447862713, 4475.753833539186, 5398.9190162146815, 5723.198752174782, 5735.7705691773535, 5763.827085187451, 4759.452662943806, 5226.921596175985, 5421.484958928895, 5493.835184944458, 5519.613627046068, 4814.662973591815, 5004.87487812793, 5526.557875370041, 5540.252065971043, 5556.082582320097, 5176.964607078675, 5431.4840090191365, 5431.511163995101, 5433.212184233185, 5436.836894893159, 4746.976265118652, 5380.623356365173, 5417.702026388586, 5506.394296627448, 5512.0983260774965, 5106.514881086291, 5269.578433725216, 5374.274472946102, 5400.567954563547, 5415.5126038655135], "value": 3982.061159975324, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.6989204318273, 753.4048101999421, 757.5341165183411, 779.6083990077713, 779.9961000194999, 13461.865381346195, 13560.728785424286, 14146.080504767184, 15037.248137593118, 15849.683006339881, 15422.460213892517, 15688.921555392208, 17122.143892805372, 17156.914215428904, 17475.300987960465, 945.7210123013728, 964.3056998960772, 969.0067680627343, 972.154225823531, 974.0016483104839, 942.4109931292942, 994.4778991029731, 1008.991008991009, 1009.9950549203515, 1012.7063151686002, 902.6254104546605, 1021.2391768132751, 1026.307242611238, 1026.4867566216892, 1037.3101887245011, 1012.3520946594185, 1018.4703953943945, 1021.7139201023688, 1026.0919086608405, 1026.14829691356, 738.8152961759561, 749.2844333661341, 752.0974830203777, 752.6801109528428, 766.5285849202756, 15374.231288435534, 15374.231288435645, 15588.532344029583, 15609.765853512206, 15611.609709757233, 16760.748588771177, 17274.740878886827, 17295.827041729674, 17308.480745577654, 17389.91305043467, 13201.933990329986, 13490.797638035438, 13636.65908352299, 13662.93168534161, 13671.17972921618, 3431.2108215110416, 3440.2775417162425, 3471.2710330830555, 3472.131967008248, 3474.096734848932, 122274.77725222723, 136102.31948840193, 137481.0, 138948.30525847408, 139852.0, 12710.428030738454, 13216.933915330554, 13256.867431325756, 13396.0, 13436.664083397798, 25179.87410062938, 25304.11435599745, 25533.61699574508, 25655.615165772528, 25990.740092598833, 7118.216996130422, 7190.892136617955, 7198.0, 7209.89185162223, 7217.06178196831, 3620.837062332201, 3669.2478042001326, 3716.591174970751, 3763.1156678180605, 3778.7732736035928, 3660.7803531788177, 3705.4997575327525, 4117.711760176759, 4123.340265557556, 4170.416141740159, 852.1691350932791, 878.7056336127341, 890.1543533643065, 892.9151730585584, 896.0009589307875, 543.0821910970448, 566.7959534567552, 571.9142128680649, 580.2601682854311, 590.9172715819789, 10035.648752293635, 10269.127124194378, 10392.636257730943, 11026.228164028442, 11694.655114661753, 486.9853904382874, 492.5050324423931, 565.6606036378222, 573.9225204597409, 592.5378205000106, 5142.691438513626, 5202.271681964528, 5244.475552444801, 5381.408045115034, 5484.972575137099, 94820.0, 126588.0, 128940.0, 130320.57435405145, 130924.0, 110538.34192487119, 113052.86947130588, 113595.0, 114011.0, 114214.85785142209, 96137.03862961421, 98241.0, 98882.50558747305, 98950.01049989408, 99226.50386747875, 8892.28861691076, 10552.261341706007, 10597.364158150536, 10783.083437907551, 10923.781524369628, 32031.83984080158, 34958.65041349554, 41925.742227733215, 42556.78721606372, 43096.78451607847, 59025.0, 59047.40952590419, 59425.81148377094, 60126.39873601208, 60307.0, 13302.733945321233, 13347.132436391603, 13780.379882905494, 13898.444062237397, 13921.025528212927, 14450.638734031312, 15004.399824007354, 15222.314995825436, 15312.004719693185, 15342.0, 13748.968827338189, 13908.721825563634, 14012.64968375758, 14177.36201870939, 14299.785503217252, 13994.720105598035, 14019.369128389042, 14164.858351416355, 14220.644483887985, 14224.288785560888, 13872.375743091787, 13921.164730116225, 14141.646458838612, 14168.929155354157, 14252.501162459264, 10149.441780701836, 10171.23715721309, 10252.794944101226, 10293.639722609672, 10509.001644843727, 10719.946400267949, 10755.139588833032, 11070.944645276722, 11187.944060279646, 11596.072314215011], "value": 7957.225260026381, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1669.8999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 119.0, 117.0, 142.0, 176.0, 153.0, 118.0, 119.0, 145.0], "value": 131.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 116.0, 155.0, 117.0, 117.0, 143.0, 124.0, 162.0, 145.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [230.0, 231.0, 248.0, 252.0, 262.0, 250.0, 314.0, 251.0, 239.0, 317.0], "value": 259.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 62.0, 64.0, 88.0, 70.0, 78.0, 62.0, 59.0, 77.0, 58.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 84.0, 75.0, 71.0, 71.0, 94.0, 86.0, 102.0, 94.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 94.0, 128.0, 88.0, 129.0, 137.0, 91.0, 99.0, 104.0, 92.0], "value": 107.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 98.0, 98.0, 98.0, 98.0, 100.0, 98.0, 99.0, 97.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 108.0, 108.0, 108.0, 132.0, 109.0, 113.0, 106.0, 108.0, 139.0], "value": 113.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 49.0, 53.0, 51.0, 52.0, 53.0, 55.0, 53.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 59.0, 62.0, 65.0, 62.0, 59.0, 69.0, 68.0, 62.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 96.0, 93.0, 92.0, 92.0, 92.0, 104.0, 92.0, 141.0, 91.0], "value": 98.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 150.0, 173.0, 151.0, 161.0, 171.0, 170.0, 155.0, 157.0, 149.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 211.0, 217.0, 203.0, 247.0, 244.0, 199.0, 197.0, 198.0, 232.0], "value": 214.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 73.0, 72.0, 75.0, 97.0, 92.0, 97.0, 101.0, 98.0], "value": 84.5, "unit": "score"}]}]} 15:37:12 INFO - Return code: 0 15:37:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:37:12 INFO - # TBPL SUCCESS # 15:37:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:37:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:37:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:37:12 INFO - 2017-05-24 15:37:12.097 screenresolution[1663:11329] starting screenresolution argv=screenresolution get 15:37:12 INFO - 2017-05-24 15:37:12.099 screenresolution[1663:11329] Display 0: 1600x1200x32@60 15:37:12 INFO - 2017-05-24 15:37:12.106 screenresolution[1664:11332] starting screenresolution argv=screenresolution list 15:37:12 INFO - Available Modes on Display 0 15:37:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:37:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:37:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:37:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:37:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:37:12 INFO - Intel Iris: 15:37:12 INFO - Chipset Model: Intel Iris 15:37:12 INFO - Type: GPU 15:37:12 INFO - Bus: Built-In 15:37:12 INFO - VRAM (Dynamic, Max): 1536 MB 15:37:12 INFO - Vendor: Intel (0x8086) 15:37:12 INFO - Device ID: 0x0a2e 15:37:12 INFO - Revision ID: 0x0009 15:37:12 INFO - Displays: 15:37:12 INFO - Display: 15:37:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:37:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:37:12 INFO - Display Serial Number: 1600x1200 60 15:37:12 INFO - Main Display: Yes 15:37:12 INFO - Mirror: Off 15:37:12 INFO - Online: Yes 15:37:12 INFO - Rotation: Supported 15:37:12 INFO - Return code: 0 15:37:12 INFO - Running post-action listener: _resource_record_post_action 15:37:12 INFO - [mozharness: 2017-05-24 22:37:12.358631Z] Finished run-tests step (success) 15:37:12 INFO - Running post-run listener: _resource_record_post_run 15:37:12 INFO - Total resource usage - Wall time: 1257s; CPU: 54.0%; Read bytes: 40725504; Write bytes: 565647872; Read time: 412; Write time: 2181 15:37:12 INFO - TinderboxPrint: CPU usage
53.9% 15:37:12 INFO - TinderboxPrint: I/O read bytes / time
40,725,504 / 412 15:37:12 INFO - TinderboxPrint: I/O write bytes / time
565,647,872 / 2,181 15:37:12 INFO - TinderboxPrint: CPU idle
2,318.6 (46.1%) 15:37:12 INFO - TinderboxPrint: CPU system
1,031.4 (20.5%) 15:37:12 INFO - TinderboxPrint: CPU user
1,679.7 (33.4%) 15:37:12 INFO - TinderboxPrint: Swap in / out
286,089,216 / 0 15:37:12 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 236829184; Write bytes: 230360576; Read time: 17621; Write time: 490 15:37:12 INFO - run-tests - Wall time: 1235s; CPU: 54.0%; Read bytes: 38493184; Write bytes: 317019136; Read time: 381; Write time: 1652 15:37:12 INFO - Running post-run listener: _upload_blobber_files 15:37:12 INFO - Blob upload gear active. 15:37:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:37:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:37:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:37:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:37:13 INFO - (blobuploader) - INFO - Open directory for files ... 15:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:37:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:13 INFO - (blobuploader) - INFO - Done attempting. 15:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:37:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:14 INFO - (blobuploader) - INFO - Done attempting. 15:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:15 INFO - (blobuploader) - INFO - Done attempting. 15:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:18 INFO - (blobuploader) - INFO - Done attempting. 15:37:18 INFO - (blobuploader) - INFO - Iteration through files over. 15:37:18 INFO - Return code: 0 15:37:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:37:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:37:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1c15223036a6f9e05106f322416cf3d10b137a88624bcaca548fb2f0d8682f793aaca0987800749e075f1c474f5254e870be9a56f00a2a6176320ae960bf399", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f9ee5d1042cb06b1703dd44dcf785e2741c2bee0f15a3beba5506358a22cca528b0e7653f9ac5e1729b8a850961053bbeffb61dc5cd3716f8accaa25e887f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bacf8ecf241dbefb116d3a801789dfe845353f139e19e851feb27f7a0aec416a204058b938742b9aed5d3dfb48699ed3b4e2a9a17b247d6d01ccef66ae525ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d24472f4edb3c0f5de60140f71ce0d5c76211b65900e2f9dcb7d228271cacb77f30a6bec6c2e1f84bf42a5f436a9eba8c328645595b29b77c0776941b06a867"} 15:37:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:37:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:37:18 INFO - Contents: 15:37:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1c15223036a6f9e05106f322416cf3d10b137a88624bcaca548fb2f0d8682f793aaca0987800749e075f1c474f5254e870be9a56f00a2a6176320ae960bf399", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f9ee5d1042cb06b1703dd44dcf785e2741c2bee0f15a3beba5506358a22cca528b0e7653f9ac5e1729b8a850961053bbeffb61dc5cd3716f8accaa25e887f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bacf8ecf241dbefb116d3a801789dfe845353f139e19e851feb27f7a0aec416a204058b938742b9aed5d3dfb48699ed3b4e2a9a17b247d6d01ccef66ae525ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d24472f4edb3c0f5de60140f71ce0d5c76211b65900e2f9dcb7d228271cacb77f30a6bec6c2e1f84bf42a5f436a9eba8c328645595b29b77c0776941b06a867"} 15:37:18 INFO - Running post-run listener: copy_logs_to_upload_dir 15:37:18 INFO - Copying logs to upload dir... 15:37:18 INFO - mkdir: /builds/slave/test/build/upload/logs 15:37:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1343.485793 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-05-24 15:37:18.122517) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 15:37:18.125758) ========= 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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/b1c15223036a6f9e05106f322416cf3d10b137a88624bcaca548fb2f0d8682f793aaca0987800749e075f1c474f5254e870be9a56f00a2a6176320ae960bf399", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f9ee5d1042cb06b1703dd44dcf785e2741c2bee0f15a3beba5506358a22cca528b0e7653f9ac5e1729b8a850961053bbeffb61dc5cd3716f8accaa25e887f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bacf8ecf241dbefb116d3a801789dfe845353f139e19e851feb27f7a0aec416a204058b938742b9aed5d3dfb48699ed3b4e2a9a17b247d6d01ccef66ae525ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d24472f4edb3c0f5de60140f71ce0d5c76211b65900e2f9dcb7d228271cacb77f30a6bec6c2e1f84bf42a5f436a9eba8c328645595b29b77c0776941b06a867"} build_url:https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012185 build_url: 'https://queue.taskcluster.net/v1/task/K5UT7hidRdqbzdcRjWvo3w/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/b1c15223036a6f9e05106f322416cf3d10b137a88624bcaca548fb2f0d8682f793aaca0987800749e075f1c474f5254e870be9a56f00a2a6176320ae960bf399", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f9ee5d1042cb06b1703dd44dcf785e2741c2bee0f15a3beba5506358a22cca528b0e7653f9ac5e1729b8a850961053bbeffb61dc5cd3716f8accaa25e887f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bacf8ecf241dbefb116d3a801789dfe845353f139e19e851feb27f7a0aec416a204058b938742b9aed5d3dfb48699ed3b4e2a9a17b247d6d01ccef66ae525ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d24472f4edb3c0f5de60140f71ce0d5c76211b65900e2f9dcb7d228271cacb77f30a6bec6c2e1f84bf42a5f436a9eba8c328645595b29b77c0776941b06a867"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 15:37:18.161026) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:37:18.161385) ========= 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.rMmHS2vtyY/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.gWIlgEJTbB/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005533 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:37:18.210253) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 15:37:18.210576) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 15:37:18.210937) ========= ========= Total master_lag: 0.17 =========