builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0252 starttime: 1497449478.22 results: success (0) revision: 45fde181a497a187d01d5412f5b72897c7520517 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.215772) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.216449) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.216904) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.240176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.240457) ========= 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.d2s4Miuz97/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.hoqrDm4AGo/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-14 07:11:18-- 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% 8.12M=0.001s 2017-06-14 07:11:18 (8.12 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158658 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.421253) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.421632) ========= 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.d2s4Miuz97/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.hoqrDm4AGo/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.023505 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.470792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:18.471160) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45fde181a497a187d01d5412f5b72897c7520517 --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 45fde181a497a187d01d5412f5b72897c7520517 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d2s4Miuz97/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.hoqrDm4AGo/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-14 07:11:18,985 truncating revision to first 12 chars 2017-06-14 07:11:18,985 Setting DEBUG logging. 2017-06-14 07:11:18,985 attempt 1/10 2017-06-14 07:11:18,985 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45fde181a497?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 07:11:19,474 unpacking tar archive at: mozilla-central-45fde181a497/testing/mozharness/ program finished with exit code 0 elapsedTime=0.750532 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:19.246684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:19.247478) ========= script_repo_revision: 45fde181a497a187d01d5412f5b72897c7520517 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:19.248348) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:19.249012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 07:11:19.268682) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-14 07:11:19.269308) ========= /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.d2s4Miuz97/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.hoqrDm4AGo/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:11:19 INFO - MultiFileLogger online at 20170614 07:11:19 in /builds/slave/test 07:11:19 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 07:11:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:11:19 INFO - {'append_to_log': False, 07:11:19 INFO - 'base_work_dir': '/builds/slave/test', 07:11:19 INFO - 'blob_upload_branch': 'Firefox', 07:11:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:11:19 INFO - 'branch': 'Firefox', 07:11:19 INFO - 'buildbot_json_path': 'buildprops.json', 07:11:19 INFO - 'config_files': ('talos/mac_config.py',), 07:11:19 INFO - 'default_actions': ('clobber', 07:11:19 INFO - 'read-buildbot-config', 07:11:19 INFO - 'download-and-extract', 07:11:19 INFO - 'populate-webroot', 07:11:19 INFO - 'create-virtualenv', 07:11:19 INFO - 'install', 07:11:19 INFO - 'setup-mitmproxy', 07:11:19 INFO - 'run-tests'), 07:11:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:11:19 INFO - 'download_minidump_stackwalk': True, 07:11:19 INFO - 'download_symbols': 'ondemand', 07:11:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:11:19 INFO - 'tooltool.py': '/tools/tooltool.py', 07:11:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:11:19 INFO - '/tools/misc-python/virtualenv.py')}, 07:11:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:11:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:11:19 INFO - 'gecko_profile': False, 07:11:19 INFO - 'gecko_profile_interval': 0, 07:11:19 INFO - 'installer_path': 'installer.exe', 07:11:19 INFO - 'log_level': 'info', 07:11:19 INFO - 'log_name': 'talos', 07:11:19 INFO - 'log_to_console': True, 07:11:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:11:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:11:19 INFO - 'opt_config_files': (), 07:11:19 INFO - 'pip_index': False, 07:11:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:11:19 INFO - 'cmd': ('bash', 07:11:19 INFO - '-c', 07:11:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:11:19 INFO - 'enabled': True, 07:11:19 INFO - 'halt_on_failure': False, 07:11:19 INFO - 'name': 'check_screen_resolution'},), 07:11:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:11:19 INFO - 'cmd': ('bash', 07:11:19 INFO - '-c', 07:11:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:11:19 INFO - 'enabled': True, 07:11:19 INFO - 'halt_on_failure': False, 07:11:19 INFO - 'name': 'check_screen_resolution'},), 07:11:19 INFO - 'run_cmd_checks_enabled': True, 07:11:19 INFO - 'suite': 'dromaeojs-e10s', 07:11:19 INFO - 'system_bits': '32', 07:11:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:11:19 INFO - 'title': 't-yosemite-r7-0252', 07:11:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:11:19 INFO - 'use_talos_json': True, 07:11:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:11:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:11:19 INFO - 'work_dir': 'build'} 07:11:19 INFO - [mozharness: 2017-06-14 14:11:19.905055Z] Running clobber step. 07:11:19 INFO - Running pre-action listener: _resource_record_pre_action 07:11:19 INFO - Running main action method: clobber 07:11:19 INFO - rmtree: /builds/slave/test/build 07:11:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:11:23 INFO - Running post-action listener: _resource_record_post_action 07:11:23 INFO - [mozharness: 2017-06-14 14:11:23.894043Z] Finished clobber step (success) 07:11:23 INFO - [mozharness: 2017-06-14 14:11:23.894238Z] Running read-buildbot-config step. 07:11:23 INFO - Running pre-action listener: _resource_record_pre_action 07:11:23 INFO - Running main action method: read_buildbot_config 07:11:23 INFO - Using buildbot properties: 07:11:23 INFO - { 07:11:23 INFO - "project": "", 07:11:23 INFO - "product": "firefox", 07:11:23 INFO - "who": "cbook@mozilla.com", 07:11:23 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 07:11:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:11:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:11:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:11:23 INFO - "stage_platform": "macosx64", 07:11:23 INFO - "basedir": "/builds/slave/test", 07:11:23 INFO - "buildnumber": 89, 07:11:23 INFO - "platform": "yosemite_r7", 07:11:23 INFO - "parent_task_id": "ASYh5tv9ScevUYqkXWa4gA", 07:11:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:11:23 INFO - "slavebuilddir": "test", 07:11:23 INFO - "taskId": "L5PbsmeEQWqn1i5uRN-y1A", 07:11:23 INFO - "branch": "mozilla-central", 07:11:23 INFO - "script_repo_revision": "production", 07:11:23 INFO - "revision": "45fde181a497a187d01d5412f5b72897c7520517", 07:11:23 INFO - "slavename": "t-yosemite-r7-0252", 07:11:23 INFO - "repo_path": "mozilla-central" 07:11:23 INFO - } 07:11:23 INFO - Finding installer, test and symbols from parent task. 07:11:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L5PbsmeEQWqn1i5uRN-y1A'}, attempt #1 07:11:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L5PbsmeEQWqn1i5uRN-y1A'}, attempt #1 07:11:24 INFO - Task dependencies: JqEz06oMQQKQZtyRY3nbHg ASYh5tv9ScevUYqkXWa4gA 07:11:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA'}, attempt #1 07:11:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 07:11:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/test_packages.json 07:11:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/target.crashreporter-symbols.zip 07:11:25 INFO - Running post-action listener: _resource_record_post_action 07:11:25 INFO - [mozharness: 2017-06-14 14:11:25.342554Z] Finished read-buildbot-config step (success) 07:11:25 INFO - [mozharness: 2017-06-14 14:11:25.342660Z] Running download-and-extract step. 07:11:25 INFO - Running pre-action listener: _resource_record_pre_action 07:11:25 INFO - Running main action method: download_and_extract 07:11:25 INFO - mkdir: /builds/slave/test/build/tests 07:11:25 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')]} 07:11:25 INFO - trying https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 07:11:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 07:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 07:11:26 INFO - Downloaded 1328 bytes. 07:11:26 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 07:11:26 INFO - Using the following test package requirements: 07:11:26 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 07:11:26 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 07:11:26 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 07:11:26 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 07:11:26 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'jsshell-mac.zip'], 07:11:26 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 07:11:26 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 07:11:26 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 07:11:26 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 07:11:26 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 07:11:26 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 07:11:26 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 07:11:26 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:11:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 07:11:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:11:26 INFO - Fetch https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 07:11:27 INFO - Content-Length response header: 59590931 07:11:27 INFO - Bytes received: 59590931 07:11:46 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:11:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 07:11:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:11:46 INFO - Fetch https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 07:11:47 INFO - Content-Length response header: 59590931 07:11:47 INFO - Bytes received: 59590931 07:12:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 07:12:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:12:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 07:12:06 INFO - Content-Length response header: 14450022 07:12:06 INFO - Bytes received: 14450022 07:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:07 INFO - trying https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 07:12:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 07:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 07:12:09 INFO - Downloaded 69617968 bytes. 07:12:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 07:12:09 INFO - mkdir: /builds/slave/test/properties 07:12:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:12:09 INFO - Writing to file /builds/slave/test/properties/build_url 07:12:09 INFO - Contents: 07:12:09 INFO - build_url:https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 07:12:09 INFO - Running post-action listener: _resource_record_post_action 07:12:09 INFO - Running post-action listener: set_extra_try_arguments 07:12:09 INFO - [mozharness: 2017-06-14 14:12:09.090480Z] Finished download-and-extract step (success) 07:12:09 INFO - [mozharness: 2017-06-14 14:12:09.090593Z] Running populate-webroot step. 07:12:09 INFO - Running pre-action listener: _resource_record_pre_action 07:12:09 INFO - Running main action method: populate_webroot 07:12:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:12:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:12:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:12:09 INFO - '--tppagecycles', 07:12:09 INFO - '7'], 07:12:09 INFO - 'tests': ['tsvgm']}}, 07:12:09 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:12:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:12:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:12:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['damp', 'tps']}, 07:12:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'tests': ['damp', 'tps']}, 07:12:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['dromaeo_dom']}, 07:12:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:12:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:12:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:12:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:12:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['a11yr', 07:12:09 INFO - 'ts_paint', 07:12:09 INFO - 'tpaint', 07:12:09 INFO - 'sessionrestore', 07:12:09 INFO - 'sessionrestore_no_auto_restore', 07:12:09 INFO - 'tabpaint']}, 07:12:09 INFO - 'other-e10s': {'tests': ['a11yr', 07:12:09 INFO - 'ts_paint', 07:12:09 INFO - 'tpaint', 07:12:09 INFO - 'sessionrestore', 07:12:09 INFO - 'sessionrestore_no_auto_restore', 07:12:09 INFO - 'tabpaint']}, 07:12:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:12:09 INFO - 'ts_paint', 07:12:09 INFO - 'tpaint', 07:12:09 INFO - 'sessionrestore', 07:12:09 INFO - 'sessionrestore_no_auto_restore', 07:12:09 INFO - 'tabpaint']}, 07:12:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:12:09 INFO - 'ts_paint', 07:12:09 INFO - 'tpaint', 07:12:09 INFO - 'sessionrestore', 07:12:09 INFO - 'sessionrestore_no_auto_restore', 07:12:09 INFO - 'tabpaint']}, 07:12:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['a11yr', 07:12:09 INFO - 'ts_paint', 07:12:09 INFO - 'tpaint', 07:12:09 INFO - 'sessionrestore', 07:12:09 INFO - 'sessionrestore_no_auto_restore', 07:12:09 INFO - 'tabpaint']}, 07:12:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['a11yr', 07:12:09 INFO - 'ts_paint', 07:12:09 INFO - 'tpaint', 07:12:09 INFO - 'sessionrestore', 07:12:09 INFO - 'sessionrestore_no_auto_restore', 07:12:09 INFO - 'tabpaint']}, 07:12:09 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:12:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:12:09 INFO - 'bloom_basic_ref']}, 07:12:09 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:12:09 INFO - 'talos_options': ['--mitmproxy', 07:12:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:12:09 INFO - '--firstNonBlankPaint'], 07:12:09 INFO - 'tests': ['quantum_pageload_google', 07:12:09 INFO - 'quantum_pageload_youtube', 07:12:09 INFO - 'quantum_pageload_amazon', 07:12:09 INFO - 'quantum_pageload_facebook']}, 07:12:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['tsvgx', 07:12:09 INFO - 'tsvgr_opacity', 07:12:09 INFO - 'tart', 07:12:09 INFO - 'tscrollx', 07:12:09 INFO - 'cart', 07:12:09 INFO - 'tsvg_static']}, 07:12:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:12:09 INFO - 'tsvgr_opacity', 07:12:09 INFO - 'tart', 07:12:09 INFO - 'tscrollx', 07:12:09 INFO - 'cart', 07:12:09 INFO - 'tsvg_static']}, 07:12:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'talos_options': ['--disable-e10s'], 07:12:09 INFO - 'tests': ['tp5o']}, 07:12:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:12:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'talos_options': ['--disable-e10s', 07:12:09 INFO - '--xperf_path', 07:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:09 INFO - 'tests': ['tp5n']}, 07:12:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:12:09 INFO - 'talos_options': ['--xperf_path', 07:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:09 INFO - 'tests': ['tp5n']}}, 07:12:09 INFO - 'talos.zip': {'path': '', 07:12:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:12:09 INFO - Running post-action listener: _resource_record_post_action 07:12:09 INFO - [mozharness: 2017-06-14 14:12:09.112600Z] Finished populate-webroot step (success) 07:12:09 INFO - [mozharness: 2017-06-14 14:12:09.112738Z] Running create-virtualenv step. 07:12:09 INFO - Running pre-action listener: _resource_record_pre_action 07:12:09 INFO - Running main action method: create_virtualenv 07:12:09 INFO - Creating virtualenv /builds/slave/test/build/venv 07:12:09 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 07:12:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:12:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:12:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:12:09 INFO - Using real prefix '/tools/python27' 07:12:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:12:09 INFO - Installing distribute.............................................................................................................................................................................................done. 07:12:12 INFO - Installing pip.................done. 07:12:12 INFO - Return code: 0 07:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:12:13 INFO - Reading from file tmpfile_stdout 07:12:13 INFO - Output received: 07:12:13 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) 07:12:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:12:13 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')]} 07:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:13 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')]} 07:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:13 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:13 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 07:12:13 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 07:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:13 INFO - 'HOME': '/Users/cltbld', 07:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:13 INFO - 'LOGNAME': 'cltbld', 07:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:13 INFO - 'MOZ_NO_REMOTE': '1', 07:12:13 INFO - 'NO_EM_RESTART': '1', 07:12:13 INFO - 'PAGER': '/bin/cat', 07:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:13 INFO - 'PWD': '/builds/slave/test', 07:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:13 INFO - 'SHELL': '/bin/bash', 07:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:13 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:13 INFO - 'USER': 'cltbld', 07:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:13 INFO - 'XPC_FLAGS': '0x0', 07:12:13 INFO - 'XPC_SERVICE_NAME': '0', 07:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:13 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 07:12:13 INFO - Cleaning up... 07:12:13 INFO - Return code: 0 07:12:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:12:13 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')]} 07:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:13 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')]} 07:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:13 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:13 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 07:12:13 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 07:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:13 INFO - 'HOME': '/Users/cltbld', 07:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:13 INFO - 'LOGNAME': 'cltbld', 07:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:13 INFO - 'MOZ_NO_REMOTE': '1', 07:12:13 INFO - 'NO_EM_RESTART': '1', 07:12:13 INFO - 'PAGER': '/bin/cat', 07:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:13 INFO - 'PWD': '/builds/slave/test', 07:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:13 INFO - 'SHELL': '/bin/bash', 07:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:13 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:13 INFO - 'USER': 'cltbld', 07:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:13 INFO - 'XPC_FLAGS': '0x0', 07:12:13 INFO - 'XPC_SERVICE_NAME': '0', 07:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:13 INFO - Downloading/unpacking psutil>=3.1.1 07:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:12:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:16 INFO - Installing collected packages: psutil 07:12:16 INFO - Running setup.py install for psutil 07:12:16 INFO - building 'psutil._psutil_osx' extension 07:12:16 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 07:12:16 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 07:12:16 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 07:12:16 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 07:12:16 INFO - building 'psutil._psutil_posix' extension 07:12:16 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 07:12:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:12:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:12:16 INFO - ^ 07:12:16 INFO - 1 warning generated. 07:12:16 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 07:12:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:16 INFO - Successfully installed psutil 07:12:16 INFO - Cleaning up... 07:12:16 INFO - Return code: 0 07:12:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:12:16 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')]} 07:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:16 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')]} 07:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:16 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:16 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 07:12:16 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 07:12:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:16 INFO - 'HOME': '/Users/cltbld', 07:12:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:16 INFO - 'LOGNAME': 'cltbld', 07:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:16 INFO - 'MOZ_NO_REMOTE': '1', 07:12:16 INFO - 'NO_EM_RESTART': '1', 07:12:16 INFO - 'PAGER': '/bin/cat', 07:12:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:16 INFO - 'PWD': '/builds/slave/test', 07:12:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:16 INFO - 'SHELL': '/bin/bash', 07:12:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:16 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:16 INFO - 'USER': 'cltbld', 07:12:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:16 INFO - 'XPC_FLAGS': '0x0', 07:12:16 INFO - 'XPC_SERVICE_NAME': '0', 07:12:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:12:17 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 07:12:17 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 07:12:17 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 07:12:17 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 07:12:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:12:18 INFO - Installing collected packages: mozsystemmonitor 07:12:18 INFO - Running setup.py install for mozsystemmonitor 07:12:18 INFO - Successfully installed mozsystemmonitor 07:12:18 INFO - Cleaning up... 07:12:18 INFO - Return code: 0 07:12:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:18 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:18 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 07:12:18 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 07:12:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:18 INFO - 'HOME': '/Users/cltbld', 07:12:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:18 INFO - 'LOGNAME': 'cltbld', 07:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:18 INFO - 'MOZ_NO_REMOTE': '1', 07:12:18 INFO - 'NO_EM_RESTART': '1', 07:12:18 INFO - 'PAGER': '/bin/cat', 07:12:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:18 INFO - 'PWD': '/builds/slave/test', 07:12:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:18 INFO - 'SHELL': '/bin/bash', 07:12:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:18 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:18 INFO - 'USER': 'cltbld', 07:12:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:18 INFO - 'XPC_FLAGS': '0x0', 07:12:18 INFO - 'XPC_SERVICE_NAME': '0', 07:12:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:19 INFO - Downloading/unpacking jsonschema==2.5.1 07:12:19 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 07:12:19 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 07:12:19 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 07:12:19 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 07:12:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:12:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:12:20 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 07:12:20 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 07:12:20 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 07:12:20 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 07:12:20 INFO - Downloading functools32-3.2.3-2.tar.gz 07:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:12:20 INFO - warning: no files found matching '*.txt' 07:12:20 INFO - no previously-included directories found matching 'build' 07:12:20 INFO - no previously-included directories found matching 'dist' 07:12:20 INFO - no previously-included directories found matching '.git*' 07:12:20 INFO - Installing collected packages: jsonschema, functools32 07:12:20 INFO - Running setup.py install for functools32 07:12:20 INFO - warning: no files found matching '*.txt' 07:12:20 INFO - no previously-included directories found matching 'build' 07:12:20 INFO - no previously-included directories found matching 'dist' 07:12:20 INFO - no previously-included directories found matching '.git*' 07:12:20 INFO - Successfully installed jsonschema functools32 07:12:20 INFO - Cleaning up... 07:12:21 INFO - Return code: 0 07:12:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:12:21 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')]} 07:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:21 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')]} 07:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:21 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:21 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 07:12:21 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 07:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:21 INFO - 'HOME': '/Users/cltbld', 07:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:21 INFO - 'LOGNAME': 'cltbld', 07:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:21 INFO - 'MOZ_NO_REMOTE': '1', 07:12:21 INFO - 'NO_EM_RESTART': '1', 07:12:21 INFO - 'PAGER': '/bin/cat', 07:12:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:21 INFO - 'PWD': '/builds/slave/test', 07:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:21 INFO - 'SHELL': '/bin/bash', 07:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:21 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:21 INFO - 'USER': 'cltbld', 07:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:21 INFO - 'XPC_FLAGS': '0x0', 07:12:21 INFO - 'XPC_SERVICE_NAME': '0', 07:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:12:21 INFO - Cleaning up... 07:12:21 INFO - Return code: 0 07:12:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:21 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')]} 07:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:21 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')]} 07:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:21 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:21 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 07:12:21 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 07:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:21 INFO - 'HOME': '/Users/cltbld', 07:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:21 INFO - 'LOGNAME': 'cltbld', 07:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:21 INFO - 'MOZ_NO_REMOTE': '1', 07:12:21 INFO - 'NO_EM_RESTART': '1', 07:12:21 INFO - 'PAGER': '/bin/cat', 07:12:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:21 INFO - 'PWD': '/builds/slave/test', 07:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:21 INFO - 'SHELL': '/bin/bash', 07:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:21 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:21 INFO - 'USER': 'cltbld', 07:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:21 INFO - 'XPC_FLAGS': '0x0', 07:12:21 INFO - 'XPC_SERVICE_NAME': '0', 07:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:21 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:21 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 07:12:21 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 07:12:21 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 07:12:21 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 07:12:22 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:23 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 07:12:23 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 07:12:23 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 07:12:23 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 07:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:23 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 07:12:23 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 07:12:23 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 07:12:23 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 07:12:23 INFO - Downloading docopt-0.6.1.tar.gz 07:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:23 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:23 INFO - Running setup.py install for blobuploader 07:12:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:24 INFO - Running setup.py install for requests 07:12:24 INFO - Running setup.py install for docopt 07:12:24 INFO - Successfully installed blobuploader requests docopt 07:12:24 INFO - Cleaning up... 07:12:24 INFO - Return code: 0 07:12:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:24 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')]} 07:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:24 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')]} 07:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:24 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:24 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 07:12:24 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 07:12:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:24 INFO - 'HOME': '/Users/cltbld', 07:12:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:24 INFO - 'LOGNAME': 'cltbld', 07:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:24 INFO - 'MOZ_NO_REMOTE': '1', 07:12:24 INFO - 'NO_EM_RESTART': '1', 07:12:24 INFO - 'PAGER': '/bin/cat', 07:12:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:24 INFO - 'PWD': '/builds/slave/test', 07:12:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:24 INFO - 'SHELL': '/bin/bash', 07:12:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:24 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:24 INFO - 'USER': 'cltbld', 07:12:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:24 INFO - 'XPC_FLAGS': '0x0', 07:12:24 INFO - 'XPC_SERVICE_NAME': '0', 07:12:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-ByY4hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-0SPwVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-5lkXiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-DSsZjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-GnDkzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-CLeda7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-MyvIRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-2SMLiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-jTTkTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:25 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-YMT2V6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-ERhqPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-W17a27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-KgxLM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-K_u7zD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-lu_bO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-G5mZx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:26 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-emyJgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:26 INFO - Running setup.py install for manifestparser 07:12:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:27 INFO - Running setup.py install for mozcrash 07:12:27 INFO - Running setup.py install for mozdebug 07:12:27 INFO - Running setup.py install for mozdevice 07:12:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:27 INFO - Running setup.py install for mozfile 07:12:27 INFO - Running setup.py install for mozhttpd 07:12:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:27 INFO - Running setup.py install for mozinfo 07:12:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:27 INFO - Running setup.py install for mozInstall 07:12:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Running setup.py install for mozleak 07:12:28 INFO - Running setup.py install for mozlog 07:12:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Running setup.py install for moznetwork 07:12:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Running setup.py install for mozprocess 07:12:28 INFO - Running setup.py install for mozprofile 07:12:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:28 INFO - Running setup.py install for mozrunner 07:12:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:29 INFO - Running setup.py install for mozscreenshot 07:12:29 INFO - Running setup.py install for moztest 07:12:29 INFO - Running setup.py install for mozversion 07:12:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:29 INFO - Cleaning up... 07:12:29 INFO - Return code: 0 07:12:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:29 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')]} 07:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:29 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')]} 07:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:29 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:29 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 07:12:29 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 07:12:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:29 INFO - 'HOME': '/Users/cltbld', 07:12:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:29 INFO - 'LOGNAME': 'cltbld', 07:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:29 INFO - 'MOZ_NO_REMOTE': '1', 07:12:29 INFO - 'NO_EM_RESTART': '1', 07:12:29 INFO - 'PAGER': '/bin/cat', 07:12:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:29 INFO - 'PWD': '/builds/slave/test', 07:12:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:29 INFO - 'SHELL': '/bin/bash', 07:12:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:29 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:29 INFO - 'USER': 'cltbld', 07:12:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:29 INFO - 'XPC_FLAGS': '0x0', 07:12:29 INFO - 'XPC_SERVICE_NAME': '0', 07:12:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:29 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-t90Ld2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:29 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)) 07:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:29 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-NQPyRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:29 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)) 07:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:29 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-P63JX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:30 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)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-cWvHSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-3tZXdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:30 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)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-XzHheQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:30 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)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-4240Rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-7_E75D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-OJVgdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:30 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)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-DV0z4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:30 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)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-7jfEnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:30 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)) 07:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:30 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-pimNdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:31 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)) 07:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-A07tgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:31 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)) 07:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-4kkt0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:31 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)) 07:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-dvH2Ep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:31 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)) 07:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-JNhqJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:31 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)) 07:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:31 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-mFDTJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:31 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)) 07:12:31 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)) 07:12:31 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)) 07:12:31 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)) 07:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:12:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:31 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 07:12:31 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 07:12:31 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 07:12:31 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 07:12:32 INFO - Downloading blessings-1.6.tar.gz 07:12:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:12:33 INFO - Installing collected packages: blessings 07:12:33 INFO - Running setup.py install for blessings 07:12:33 INFO - Successfully installed blessings 07:12:33 INFO - Cleaning up... 07:12:33 INFO - Return code: 0 07:12:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:12:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:33 INFO - Reading from file tmpfile_stdout 07:12:33 INFO - Current package versions: 07:12:33 INFO - blessings == 1.6 07:12:33 INFO - blobuploader == 1.2.4 07:12:33 INFO - docopt == 0.6.1 07:12:33 INFO - functools32 == 3.2.3-2 07:12:33 INFO - jsonschema == 2.5.1 07:12:33 INFO - manifestparser == 1.1 07:12:33 INFO - mozInstall == 1.13 07:12:33 INFO - mozcrash == 1.0 07:12:33 INFO - mozdebug == 0.1 07:12:33 INFO - mozdevice == 0.51 07:12:33 INFO - mozfile == 1.2 07:12:33 INFO - mozhttpd == 0.7 07:12:33 INFO - mozinfo == 0.10 07:12:33 INFO - mozleak == 0.1 07:12:33 INFO - mozlog == 3.4 07:12:33 INFO - moznetwork == 0.27 07:12:33 INFO - mozprocess == 0.25 07:12:33 INFO - mozprofile == 0.28 07:12:33 INFO - mozrunner == 6.13 07:12:33 INFO - mozscreenshot == 0.1 07:12:33 INFO - mozsystemmonitor == 0.3 07:12:33 INFO - moztest == 0.8 07:12:33 INFO - mozversion == 1.4 07:12:33 INFO - psutil == 3.1.1 07:12:33 INFO - requests == 1.2.3 07:12:33 INFO - wsgiref == 0.1.2 07:12:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:33 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')]} 07:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:33 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')]} 07:12:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:33 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:33 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 07:12:33 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 07:12:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:33 INFO - 'HOME': '/Users/cltbld', 07:12:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:33 INFO - 'LOGNAME': 'cltbld', 07:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:33 INFO - 'MOZ_NO_REMOTE': '1', 07:12:33 INFO - 'NO_EM_RESTART': '1', 07:12:33 INFO - 'PAGER': '/bin/cat', 07:12:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:33 INFO - 'PWD': '/builds/slave/test', 07:12:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:33 INFO - 'SHELL': '/bin/bash', 07:12:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:33 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:33 INFO - 'USER': 'cltbld', 07:12:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:33 INFO - 'XPC_FLAGS': '0x0', 07:12:33 INFO - 'XPC_SERVICE_NAME': '0', 07:12:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:33 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)) 07:12:33 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)) 07:12:33 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)) 07:12:33 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)) 07:12:33 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)) 07:12:33 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)) 07:12:33 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)) 07:12:33 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)) 07:12:33 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)) 07:12:33 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:12:33 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 07:12:33 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 07:12:33 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 07:12:33 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 07:12:35 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:12:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:12:35 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)) 07:12:35 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)) 07:12:35 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)) 07:12:35 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)) 07:12:35 INFO - Installing collected packages: marionette-driver 07:12:35 INFO - Running setup.py install for marionette-driver 07:12:35 INFO - Successfully installed marionette-driver 07:12:35 INFO - Cleaning up... 07:12:35 INFO - Return code: 0 07:12:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:12:35 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')]} 07:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:35 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')]} 07:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:35 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 0x10929cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba04aad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, '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.d2s4Miuz97/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.hoqrDm4AGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 07:12:35 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 07:12:35 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 07:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:35 INFO - 'HOME': '/Users/cltbld', 07:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:35 INFO - 'LOGNAME': 'cltbld', 07:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:35 INFO - 'MOZ_NO_REMOTE': '1', 07:12:35 INFO - 'NO_EM_RESTART': '1', 07:12:35 INFO - 'PAGER': '/bin/cat', 07:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:35 INFO - 'PWD': '/builds/slave/test', 07:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:35 INFO - 'SHELL': '/bin/bash', 07:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:35 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:35 INFO - 'USER': 'cltbld', 07:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:35 INFO - 'XPC_FLAGS': '0x0', 07:12:35 INFO - 'XPC_SERVICE_NAME': '0', 07:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:12:35 INFO - Cleaning up... 07:12:35 INFO - Return code: 0 07:12:35 INFO - Running post-action listener: _resource_record_post_action 07:12:35 INFO - Running post-action listener: _start_resource_monitoring 07:12:35 INFO - Starting resource monitoring. 07:12:35 INFO - [mozharness: 2017-06-14 14:12:35.942488Z] Finished create-virtualenv step (success) 07:12:35 INFO - [mozharness: 2017-06-14 14:12:35.943539Z] Running install step. 07:12:35 INFO - Running pre-action listener: _resource_record_pre_action 07:12:35 INFO - Running main action method: install 07:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:36 INFO - Reading from file tmpfile_stdout 07:12:36 INFO - Detecting whether we're running mozinstall >=1.0... 07:12:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:12:36 INFO - Reading from file tmpfile_stdout 07:12:36 INFO - Output received: 07:12:36 INFO - Usage: mozinstall [options] installer 07:12:36 INFO - Options: 07:12:36 INFO - -h, --help show this help message and exit 07:12:36 INFO - -d DEST, --destination=DEST 07:12:36 INFO - Directory to install application into. [default: 07:12:36 INFO - "/builds/slave/test"] 07:12:36 INFO - --app=APP Application being installed. [default: firefox] 07:12:36 INFO - mkdir: /builds/slave/test/build/application 07:12:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:12:58 INFO - Reading from file tmpfile_stdout 07:12:58 INFO - Output received: 07:12:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:12:58 INFO - Running post-action listener: _resource_record_post_action 07:12:58 INFO - [mozharness: 2017-06-14 14:12:58.130106Z] Finished install step (success) 07:12:58 INFO - [mozharness: 2017-06-14 14:12:58.130233Z] Running setup-mitmproxy step. 07:12:58 INFO - Running pre-action listener: _resource_record_pre_action 07:12:58 INFO - Running main action method: setup_mitmproxy 07:12:58 INFO - Skipping: mitmproxy is not required 07:12:58 INFO - Running post-action listener: _resource_record_post_action 07:12:58 INFO - [mozharness: 2017-06-14 14:12:58.131056Z] Finished setup-mitmproxy step (success) 07:12:58 INFO - [mozharness: 2017-06-14 14:12:58.131200Z] Running run-tests step. 07:12:58 INFO - Running pre-action listener: _resource_record_pre_action 07:12:58 INFO - Running main action method: run_tests 07:12:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:12:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:12:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:12:58 INFO - 2017-06-14 07:12:58.146 screenresolution[2902:43301] starting screenresolution argv=screenresolution get 07:12:58 INFO - 2017-06-14 07:12:58.149 screenresolution[2902:43301] Display 0: 1600x1200x32@60 07:12:58 INFO - 2017-06-14 07:12:58.154 screenresolution[2903:43306] starting screenresolution argv=screenresolution list 07:12:58 INFO - Available Modes on Display 0 07:12:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:12:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:12:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:12:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:12:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:12:58 INFO - Intel Iris: 07:12:58 INFO - Chipset Model: Intel Iris 07:12:58 INFO - Type: GPU 07:12:58 INFO - Bus: Built-In 07:12:58 INFO - VRAM (Dynamic, Max): 1536 MB 07:12:58 INFO - Vendor: Intel (0x8086) 07:12:58 INFO - Device ID: 0x0a2e 07:12:58 INFO - Revision ID: 0x0009 07:12:58 INFO - Displays: 07:12:58 INFO - Display: 07:12:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:12:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:12:58 INFO - Display Serial Number: 1600x1200 60 07:12:58 INFO - Main Display: Yes 07:12:58 INFO - Mirror: Off 07:12:58 INFO - Online: Yes 07:12:58 INFO - Rotation: Supported 07:12:58 INFO - Return code: 0 07:12:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:12:58 INFO - Python 2.7.3 07:12:58 INFO - Return code: 0 07:12:58 INFO - grabbing minidump binary from tooltool 07:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:58 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 0x108ff2d30>, '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 0x108fbccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:12:58 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 07:12:58 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 07:12:58 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 07:12:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:12:58 INFO - Return code: 0 07:12:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:12:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:12:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:12:58 INFO - ENV: RUST_BACKTRACE is now 1 07:12:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:12:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:12:58 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/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 07:12:58 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/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0252 --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 07:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d2s4Miuz97/Render', 07:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:58 INFO - 'HOME': '/Users/cltbld', 07:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:58 INFO - 'LOGNAME': 'cltbld', 07:12:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:12:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:58 INFO - 'MOZ_NO_REMOTE': '1', 07:12:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:12:58 INFO - 'NO_EM_RESTART': '1', 07:12:58 INFO - 'PAGER': '/bin/cat', 07:12:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:58 INFO - 'PWD': '/builds/slave/test', 07:12:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:58 INFO - 'RUST_BACKTRACE': '1', 07:12:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:12:58 INFO - 'SHELL': '/bin/bash', 07:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hoqrDm4AGo/Listeners', 07:12:58 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 07:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:58 INFO - 'USER': 'cltbld', 07:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:58 INFO - 'XPC_FLAGS': '0x0', 07:12:58 INFO - 'XPC_SERVICE_NAME': '0', 07:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:58 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/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 07:12:58 INFO - mozversion application_buildid: 20170614055204 07:12:58 INFO - mozversion application_changeset: 45fde181a497a187d01d5412f5b72897c7520517 07:12:58 INFO - mozversion application_display_name: Nightly 07:12:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:12:58 INFO - mozversion application_name: Firefox 07:12:58 INFO - mozversion application_remotingname: firefox 07:12:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:12:58 INFO - mozversion application_vendor: Mozilla 07:12:58 INFO - mozversion application_version: 56.0a1 07:12:58 INFO - mozversion platform_buildid: 20170614055204 07:12:58 INFO - mozversion platform_changeset: 45fde181a497a187d01d5412f5b72897c7520517 07:12:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:12:58 INFO - mozversion platform_version: 56.0a1 07:12:58 INFO - using testdate: 1497449578 07:12:58 INFO - actual date: 1497449578 07:12:58 INFO - starting webserver on 'localhost:65011' 07:12:58 INFO - SUITE-START | Running 2 tests 07:12:58 INFO - TEST-START | dromaeo_css 07:12:58 INFO - operating with platform_type : mac_ 07:12:58 INFO - Initialising browser for dromaeo_css test... 07:12:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:65011/getInfo.html -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpq2_AAV/profile 07:12:58 INFO - TEST-INFO | started process 2913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:65011/getInfo.html -foreground) 07:13:32 INFO - TEST-INFO | 2913: killed by SIGTERM 07:13:32 INFO - Browser initialized. 07:13:32 INFO - Running cycle 1/1 for dromaeo_css test... 07:13:32 INFO - TEST-INFO | started process 2919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpq2_AAV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:34 INFO - PID 2919 | 2017-06-14 07:13:34.169 plugin-container[2920:43779] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 07:13:34 INFO - PID 2919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:13:34 INFO - PID 2919 | 2017-06-14 07:13:34.169 plugin-container[2920:43779] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 07:13:34 INFO - PID 2919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:13:34 INFO - PID 2919 | 2017-06-14 07:13:34.169 plugin-container[2920:43779] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:13:35 INFO - PID 2919 | 2017-06-14 07:13:35.042 plugin-container[2921:43827] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 07:13:35 INFO - PID 2919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:13:35 INFO - PID 2919 | 2017-06-14 07:13:35.043 plugin-container[2921:43827] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 07:13:35 INFO - PID 2919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:13:35 INFO - PID 2919 | 2017-06-14 07:13:35.043 plugin-container[2921:43827] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:13:44 INFO - PID 2919 | RSS: Main: 254627840 07:13:44 INFO - PID 2919 | 07:16:47 INFO - PID 2919 | Cycle 1(1): loaded http://localhost:65011/tests/dromaeo/cssquery-dojo.html (next: http://localhost:65011/tests/dromaeo/cssquery-ext.html) 07:16:50 INFO - PID 2919 | RSS: Main: 268382208 07:16:50 INFO - PID 2919 | 07:19:51 INFO - PID 2919 | Cycle 1(1): loaded http://localhost:65011/tests/dromaeo/cssquery-ext.html (next: http://localhost:65011/tests/dromaeo/cssquery-jquery.html) 07:19:52 INFO - PID 2919 | RSS: Main: 278388736 07:19:52 INFO - PID 2919 | 07:22:54 INFO - PID 2919 | Cycle 1(1): loaded http://localhost:65011/tests/dromaeo/cssquery-jquery.html (next: http://localhost:65011/tests/dromaeo/cssquery-mootools.html) 07:22:54 INFO - PID 2919 | RSS: Main: 282312704 07:22:54 INFO - PID 2919 | 07:25:56 INFO - PID 2919 | Cycle 1(1): loaded http://localhost:65011/tests/dromaeo/cssquery-mootools.html (next: http://localhost:65011/tests/dromaeo/cssquery-prototype.html) 07:25:57 INFO - PID 2919 | RSS: Main: 280555520 07:25:57 INFO - PID 2919 | 07:29:00 INFO - PID 2919 | Cycle 1(1): loaded http://localhost:65011/tests/dromaeo/cssquery-prototype.html (next: http://localhost:65011/tests/dromaeo/cssquery-yui.html) 07:29:00 INFO - PID 2919 | RSS: Main: 282206208 07:29:00 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | Cycle 1(1): loaded http://localhost:65011/tests/dromaeo/cssquery-yui.html (next: http://localhost:65011/tests/dromaeo/cssquery-dojo.html) 07:32:02 INFO - PID 2919 | RSS: Main: 280530944 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | __start_tp_report 07:32:02 INFO - PID 2919 | _x_x_mozilla_page_load 07:32:02 INFO - PID 2919 | _x_x_mozilla_page_load_details 07:32:02 INFO - PID 2919 | |i|pagename|runs| 07:32:02 INFO - PID 2919 | |0;dojo.html;3419.9911026247255;3456.2741824216914;3502.667246611572;3558.6441355864417;3591.138126849555;6171.4445699887;6171.475424588915;6176.227971503562;6197.318294987554;6209.254889413265;6149.893019256532;6180.969095154529;6193.690315484219;6195.752169913209;6260.373962603738;5406.972965135169;5422.186671999212;5461.726913654321;5483.369412517552;5573.773769770664;5501.174823776446;5531.308586426697;5538.947599955995;5548.472895074961;5560.7219639018085;4561.977190114061;5421.21392398104;5440.619156659035;5464.12573988162;5482.91775623366;4394.384786129945;4431.468223813131;5365.651232669864;5421.0784166691365;5457.290552228224;5362.302900622894;5393;5433.755481003364;5476.151196564539;5480.534154596864;6126.29547602027;6222.813315600494;6238.064290356438;6245.906311405332;6252.87494250117;6725.831854203684;6732.596044237362;6738.22510411304;6746.325367463214;6777.8644427111185;6625.271220165778;6687.899681504781;6700.83247918801;6706.798796036127;6824.317568243186;6266.561340706198;6296.464800491964;6339.904901426528;6345.904811427832;6354.078658594571;5972.462478376967;6839.281875403011;6878.793636190822;6967.895481567679;7001.36987671112;6384.744610215539;6585.5719378240265;6604.1414616100565;6629.900551491732;6639.933600663931;5744.684042377626;6619.073329733841;6642.501812363996;6649.368310010541;6766.018927255521;5611.438856114356;6622.337766223339;6668.86662266752;6697.8995315070315;6732.562383445158;5745.368009518949;6519.152510173742;6633.170853643294;6730.461563074942;6756.831079223059;5356.062689029404;5369.6509726867625;5369.973150134224;5375.838724838261;5376.059189641797;4630.32860235264;4669.95330046702;4675.438947326343;4684.250519916865;4707.929381059287;6247.9062814056915;6266.655333956676;6342.778002769881;6377.936220637827;6406.903896441557;6109.877802444015;6419.45434638061;6752.898706519406;6830.93169068303;6866.965665171642;5184;5834.795782147605;6370.617762934146;6377.649229292433;6389.712462939179;5583.3858275589655;5753.05074662676;6008.1588577598295;6012.338642749294;6059.333473317932;5306.681599104143;6912.723491060502;6983.1620205575655;6996.895046574407;6999.335063168991;6717.56335838169;6766.15423072116;6805.285445028201;6840.281770414035;6841.213260475109;6950.756723514653;6953.061336719478;6961.443084553122;6975.476839236976;6983.615901125487;6659.53383263167;6685.565438246498;6686.665666716547;6687.732490700318;6715.865682686416;6615.801525954229;6715.05989161508;6854.074699915447;6854.725810967506;6862.382385585322;6490.221173459215;6505.121808555785;6540.640264785482;6642.4021838034805;6652.767153149616;6523;6621.1392518972225;6631.767888123893;6635.9004614929845;6640.667966601747;6424.678766061585;6483.09236706852;6495.57778744399;6500.187476565429;6515.771947981798;5631.267935168566;6461.386168314003;6478.643674597943;6565.409113179837;6609.801705948829;6288.245410550763;6301.149344838388;6301.275353334242;6322.430981211713;6348.047792830927 07:32:02 INFO - PID 2919 | |1;ext.html;746.5520687587448;768.4083255892962;807.4186585658324;815.0586073085589;824.1346586084611;33827.66172338276;38510.61489385105;39291.41062884059;39631.801840990825;39683.80158099209;32531;39047.804760976156;39444;39544.813655590304;40154.59845401545;8360.205780450848;8419.621117049748;8459.323254139685;8501.74494765155;8503.65985360588;7124.323189297009;8276.131006244317;8416.705415310465;8424;8461.450005749637;7710.344620707247;8418.03192632849;8452.577371131449;8460.407771456;8473.279771219453;7228.277172282782;7810.843783124335;8383.371247156458;8449.408541402105;8458.365622578269;8247.21651443112;8263.008438987299;8298.751037468886;8309.418340716153;8314.625841837133;37576.06059848498;37815;37911;37914.43128353077;37923.62076379256;3562.020444377817;3569.26829999852;3615.9096022599647;3617.439296908983;3631.673149416565;30461.23846903823;30467.847660761778;30470.933517326786;30474;30535.236619084477;3024.74289685377;3077;3098.395812816502;3147.0244224290545;3147.9370412591848;233721.9999999983;235784;235804;293437;297872;38925.805370972965;47777.28334074992;47790.761046194544;47812.28281575779;48465.273020904715;51314;51323.74338128285;51459;51475;51546;14249.430022798972;14311.713765724628;14318;14319.284035798168;14409;9452.196563292197;11225;11245.044171245372;11388.94305528467;11402.087832973342;13906.51327203543;13998.650033749034;14000.859991399955;14015.649608759863;14136.50522231717;5278.868028299324;5898.793542226002;5974.492168165754;6037.124616930609;6071.726772295169;1002.6290272599039;1020.8213562626511;1024.0015984415136;1072.292287090532;1078.5254488025244;23637.645435318485;30746.231344216125;31094.844525777225;31446.213844654067;31631.20921976923;1106.115107913666;1153.988460115405;1155.9768804623861;1159.2175281684829;1169.1990986174328;10275.897241027644;12654;12712.237265764084;12738.55415060487;12779.488820447265;266553;267004;316965;318675;318693;51829;52070;52165;52169.2174617381;52293.47706522886;1413.738458385202;1501.737195990719;1502.8497150285102;1510.4033906606828;1538.9769153462482;22321.553568928204;23118;24472.388190295384;28183.71816281811;30153.396932061674;52525.68685782886;53549.73225133849;53685.92628147493;53762.46237537575;53818.73090634522;237683;239588;239912;288787;291690;34640.30719385648;34938.82530587432;35264.29471410506;35393;35490.29019419649;42223;42731.14537709291;43467.69596912098;43554.25782968646;44502.66492005245;28907.566386503797;29619.861635647234;30181.69818301789;30945.0716478506;31729.8413507931;30337.393252135276;30883.0735077948;31135.84432077825;31204;31712.8900488491;35760;37292.813535932146;38060.04849878775;38060.2387952245;38121;29936;30564.123072308554;30918.923225542552;31280.061598152093;32341.35317293688;18790.53023674419;24713.752862471145;24946.251612451655;25203.873980630713;25296.873515632305 07:32:02 INFO - PID 2919 | |2;jquery.html;495.9628027897908;496.1391984906187;498.76558017731674;511.2484647568075;523.6779380680878;20743.792562074374;21869.250459963205;21975.23086691966;22619.434514137134;22734.545309093828;22819.543609127806;23344;23424.88287558564;23572.292831215047;23639.763602364015;6065.848353791157;6234.345393733661;6279;6299.464545513614;6311.400416960404;6089.330173680892;6204.534659900503;6221.191245138126;6227.750889964396;6234.0337247726675;5980.43185897339;6237.376262373772;6280.120783090371;6282.528810339243;6294.968525157367;6213.782517611885;6249.468795152417;6275.215598050244;6275.341089185616;6281.591696539733;5699.829005129853;5702.0021496238;5706.058500347479;5707.229524014288;5740.196372507852;27194;28975.710242897723;29091.836326546912;29201.85399072991;29407.41185176306;2209.171560664751;2244.7867452591977;2247;2266.9232114745528;2267.444476103365;17757.28970841165;18029.918204907746;18203.726944095848;18208;18214.90892545542;2159.3845753960045;2200.19692812124;2230.654248591471;2253.6506841439605;2260.480089579406;160841;164380.71238575282;166728;168274;184467;13094.214347139203;13160.934195329153;13568.525101621395;14063.226522541154;14684.486042988445;16420.83579164217;19302.517437063903;19311.42065738002;19361.61276774485;19397.4180774577;9439.150476457151;9454.810903782023;9489.525523723787;9594.66418675343;9782.461964591874;7836.921630783733;8565.95717021411;8968.461892286483;8981.236594889508;9065.728028159167;7394.112706475257;7427.480076394709;8422.883533092794;8939.541626052758;8948.373613847098;2444.7799698026906;2552.3746682063015;2564.910228142006;2569.5760199567235;2704.5402281612173;817.721974528663;940.9435433873989;953.8998405167496;1014.3558840136567;1015.4770293298957;19762.308319209045;20052.897090660153;20972.895135524224;24392.75607243905;27050.72949270482;1025.9538320775582;1041.8695715149147;1063.2079101069753;1070.6038765656756;1071.3893080943787;10824.56701731922;11232.887671123348;11241.21311508203;11293.096552275798;11349.542228830953;588257;588377;588451;603460;604401;155665.22167389316;158615.20692396694;159276;160619;164500;1062.490004797686;1208.8972437342925;1256.4911210959576;1268.2834198677717;1290.270996886756;19246.7112993308;21537.7077375358;21732.913354332537;21899.452513687283;23168;54063.72968135292;66325;69285.30714692788;69312;69544;89389.10610893808;89967.2006559852;90998;92711.60932586151;93668;11175.49710263024;11278;11308.604198853001;11351.37567433799;11359.977602015528;17019;17920.372786952394;18426.907865461122;18499.167537460577;18571;14061.382940961512;15104.244787760788;15116.54650360491;15231.847681523042;15235.162066086028;13271.66820829487;13272.800907986195;13424.731505370033;13547.59357219285;13777.966652500902;15371.077735335468;15432.691346172789;15566.610834729223;15569;15773.526794196192;11525.654230373102;11621.593244236412;11625;11642.01042911362;11655.825162622397;14831.11013339203;14881.330340134937;14895.106293622417;14917.328720207399;14918.104913705212 07:32:02 INFO - PID 2919 | |3;mootools.html;1339.236635117983;1343.892488600912;1373.2858913365048;1423.0181174989264;1449.2608769527537;13392.263425511594;13666.794998075024;13740.450381984734;13783.586492405244;13824.96312776542;11735.941320293408;12828.294443805611;13349.733005339887;13774.173549587033;13869.514566990158;2725.4685336359366;2753.5869619557025;2767.3220061085062;2777.9305517362027;2779.6247506586656;2689.3007817967273;2750.147454289177;2752.1330780804083;2752.325680208342;2755;2285.4514916420108;2688.7714544263763;2736.8905243790223;2740.5067087924167;2745.807793454459;2323.9767602323927;2365.0185173153122;2383.380321116505;2610.282172402576;2766.280767000574;2750.40866213765;2761.0888406825698;2768.307923019245;2768.9723102768917;2770.473610014091;13457.798133027914;13517.932410337886;13580.185188888698;13582.796258056138;13620.523281685093;7372.594507302097;7675.270849269311;7685.692572297102;7692.384609231248;7695.268949449794;4504.594586487199;4695.906081878377;4803.951960480385;4827.300041493963;4834.8791280218275;1188.233589334874;1364.8976326775432;1393.470481217131;1414.455434657654;1416.2706206303762;19920.501987450283;19930.402087937386;19969.800301997086;20102.698459522973;20126.19495220204;6468.482521398277;6481.222253329536;6482.254540727782;6492.383223593751;6637.767678131242;14157.00900936945;14160.150390976574;14167.64580885486;14175.149491030572;14225.78661320081;7241.203467618558;7376.7049318026675;7395.038644976226;7402.259774022662;7405.9629701851145;6594.307597702268;6794.932050679528;6819.352161544645;6852;6861.588304701734;4933.383327084114;5012;5060.822871199491;5072.807890145813;5166.689998600096;1450.3980847948194;1454.2583282525989;1467.7945087687733;1471.3231913319778;1483.940642374293;801.887735717;811.9675212991532;822.7494208802586;837.2130197614223;857.5540718826301;7119.786406407816;7372.336489715952;7393.593352365564;7789;8011.519308841488;672.122879642062;682.4097155960158;703.7853454696367;704.6018999265399;706.6325510734389;4178.639788130844;4277;4680.625549955996;5028.094942910311;5232.1628539433505;15839.336869628754;16885;17769.555761105818;18509;18511.90744046298;17899;17989.550261243312;18183.727244091348;18215.45353639393;18593.90703046503;9271.211946984447;9356.204722598519;9399.671011514563;9421;9422.481763503074;7193.604351760703;7283.2352602975925;7795.766127016199;7854.489458185429;8034.196580341919;15496.225188740744;15553.222338883237;16324.591885202964;16373.263203156124;16404.753928691316;16474.258658360162;16542.255598498337;16834.326626934784;16871.240794164045;16884.551739997387;8343.666253349798;9740.707778766648;9790.657326993522;9906.455144967096;9928.255380846322;12028.819567706667;12296.20074695142;12456.564020259248;12568.057395695543;12628.87371126277;8520;9834.852477212704;10064.094231519199;10216.897831021595;10445.895541044492;9518.857217141887;9726.902730972599;10084.042016008467;10244.334118282288;10335.534900929326;9071.818563628558;9131.908680913371;9140.680076197566;9143.725688229364;9149.222316103205;6060.545459090497;6189.6286222826775;6226.719797609027;6265.467435268053;6468.8706225874275;6758.4931130166415;6851.657417129024;7077.433805295461;7092.964535177498;7146.9285307146265 07:32:02 INFO - PID 2919 | |4;prototype.html;431.93520971854196;449.133172976156;454.3979227523531;466.9929951050735;469.4085452330063;6030.276366835986;6034.69826508675;6278.34077421871;6532.608043517393;6573.73705051798;4971.676841005332;5547.72261386931;6299.551103246256;6366.363363663643;6438.227412710469;5036.471170527097;5046.64673472858;5049.570786483153;5071.518205770446;5081.187010078388;5076.974615126919;5079.644424890259;5085.28805967163;5089.312942752718;5089.9100899100895;4361.956380436187;4388.956110438887;5035.370578677665;5047.293378926953;5084.144287335133;4273.87178384649;4600.6549508786975;4907.558319751204;4926.852194434173;5018.272350509193;3946.6842652587725;3975.6819454443576;4550.431196100488;4555.362249285103;4569.154706379297;5640.5769567282205;5804.361520232771;6271.648131950518;6295.905561416583;6319.33646967071;5982.940170598282;5983.012802887519;6001.159837622693;6006.909896351558;6018.097285407181;6946.895796563055;6949.861002779967;6959.930400695979;6960.582365058062;6960.756373526902;5199;5206.947930520684;5224.712640804754;5233.921491177635;5235.110031294689;6362.236531616236;7232.60220687862;7274.381677557414;7285.635718214068;7300.7444739434395;4356.760378179167;4980.452150263468;5383.43473935239;5424.728763561806;5478.479544443271;4140.60664236897;4505.098980203972;5424.833660762955;5435.864103397446;5486.286782718301;5876.1794338717655;6020.638761674313;6024.969875150597;6027.48766354856;6042.425969532887;5765.4522820332;6159.353267906805;6580.7496575650475;6960.651967401711;6974.721011159599;1303.9739205216033;1624.3746157729358;1626.2600516764844;1712.161041089857;1860.5627677495777;948.7390967483997;1123.2081382625393;1140.5152810055692;1157.9536818527333;1165.2425923149851;5410.59420543453;5552.5280351169795;6389.361063893573;6443.7744678936015;6492.64290464029;6714.83212919681;6933.5493192942295;7117.3238542338395;7132.072830531998;7141.036724617783;5249.448807875118;5641.63051518355;6504.934950650528;6506.186726659168;6520.543561950618;3411.880584179542;3672.7245456590863;3690.5755838078426;3702.7963462009848;3706.221693444353;7234.5659260444545;7247.963760181271;7271.963640181765;7349.7950614815345;7366.484346095722;5821.592488525763;6410.410170304551;7133.928660713326;7247.4926755126635;7252.311030452204;6457.644829534421;6629.740349333708;7001.649917504104;7266.600336981517;7282.745103921443;6235.719392627433;6336.619802811846;6967.825804354931;7046.4010559101;7104.467164962752;6246;6297.740451909728;6985.895211571728;7001.929980700128;7009.684564194521;7264.183954011497;7281.417486601165;7291.708331666886;7294.161171465137;7363.852722945404;3772.850059668478;4254.723442976197;4468.530804265635;4578.748168850745;4825.420949486084;6599.769008084694;7108.680109394986;7159.534630249017;7164.570125792469;7171.605561694157;5373.570114390917;5725.423559398204;6298.748050077946;6857.27998560144;6890.552114112566;5976.521878249817;6730.8653826924165;6742.42689371409;6779.864402712017;6796.694148763417;5676.489115979582;6043.758249669964;6672.866542669217;6698.66506674674;6731.495137864582;5945.583809133319;6281.246250449975;6334.239891213084;6726.630035348103;6745.865082698417;5588.86027849307;5838.795642152504;6645.833854153685;6650.700718467778;6678.866422671616 07:32:02 INFO - PID 2919 | |5;yui.html;758.4728613613537;782.2295039386204;822.0833770346065;862.5988915154454;868.2966796894516;16820.411285605005;16961.406350777725;17303.30786768528;17350.305987760476;17384.478465646036;10625.893741062606;13345.799813002788;13503.459861605525;16167.43413980511;16833.579160520992;980.8626792249091;989.0603926270054;989.2778271861546;998.8052329795693;1000.2048371544603;991.7471044883545;993.5975929748463;994.5375400438794;995.5370752600039;996.1482932093065;964.3249725192359;1047.455323231919;1047.5547892145846;1048.1667074675652;1050.112654806687;1000.5147503460815;1069.7272195590074;1077.0252921106412;1078.6332646900053;1083.7182332593543;789.4947233770391;794.2970471133032;794.3287921706149;794.3526026288614;798.1340245833278;17425.825741742672;17743.29026838925;17923.37268195607;17961.461156165336;18140.818591814044;17426.825731742647;17640.91179544094;17778.555536111573;17785.377511787156;17976.820231797778;14822.629434264121;14831.92584037073;14836.406543738238;15028.624284392869;15029.849701502955;3578.910527236814;3650.8539658413633;3668.3396988542054;3677.3380791457525;3747.2318174774105;139306.30346848202;140879.29560352236;142352.28823855813;143251.28374358165;148648;13558.661033474242;13972.860271397156;13981.231032293119;14003.579892603238;14171.64570885736;26394.6040809388;26575.33561660974;27059.458810823675;27071.458570828472;27087.593686094726;7780.610969451618;7794.961025194838;7845.3723702104835;7852.254035866584;7852.293293603603;3917.745346552465;3977.025628720924;3981.8009099544906;3989.1024519482885;3997.320455522568;4207.558206388347;4319.244132276839;4597.540245975376;4606.124836281163;4652.185867473178;806.9919300807035;946.9195118415012;956.9043445255282;960.9471479068718;961.4183419031525;545.6344249352943;592.0054308761237;652.2433976587137;659.9373059559334;683.9281875403011;9954.800903981879;11695.766084678258;11977;12094.818577721342;12303.630891073282;618.913352130702;657.3893959798513;664.4153145232165;683.5181197255922;685.7565564224628;5428.4842939920645;5630.099184130601;5939.168516407706;6007.549433792483;6595.934040659628;131068;131542.34228828794;132677;134373;135278;113936;116478;118891.8110818898;119479.40260298643;127693;102627.48686256522;103148.48425757974;103275;103694;111068.44465777781;12085.335306558223;12439.626811195678;12441.875581244434;12561.246325220516;12965.092443528862;45692.400765973034;46262.768686156356;46826;47982.520174797806;47985;78667.60666196632;82516.76224856512;84203.5789821047;84638;87613.1238687605;16950.745738814174;17300.961942283502;17616.471505854846;17948;17956.371526996492;18513.166907489463;19017.33439329617;19024.71462928085;19161.52096197606;19276.518087047934;17119.93080914847;17138.97166170034;17901.015444150697;17910.641787163924;18042.09789510493;17049.40327088546;17940.013299268667;18098.00460974659;18108.547286317946;18286.542836429195;16596.668066638358;17619.471415857544;17860.374886878897;18042.458726238234;18270.634587308446;10877.401742904216;11679.883201167879;11708;12274;12523.311217883103;12948.09363344557;12992.220466772023;13093.345332733516;13114.540991065267;13567.79648305296 07:32:02 INFO - PID 2919 | __end_tp_report 07:32:02 INFO - PID 2919 | __start_cc_report 07:32:02 INFO - PID 2919 | _x_x_mozilla_cycle_collect,465 07:32:02 INFO - PID 2919 | __end_cc_report 07:32:02 INFO - PID 2919 | __startTimestamp1497450722665__endTimestamp 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | ------- Summary: start ------- 07:32:02 INFO - PID 2919 | Number of tests: 6 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | [#0] dojo.html Cycles:165 Average:6133.34 Median:6370.62 stddev:772.26 (12.1%) stddev-sans-first:744.72 07:32:02 INFO - PID 2919 | Values: 3420.0 3456.3 3502.7 3558.6 3591.1 6171.4 6171.5 6176.2 6197.3 6209.3 6149.9 6181.0 6193.7 6195.8 6260.4 5407.0 5422.2 5461.7 5483.4 5573.8 5501.2 5531.3 5538.9 5548.5 5560.7 4562.0 5421.2 5440.6 5464.1 5482.9 4394.4 4431.5 5365.7 5421.1 5457.3 5362.3 5393.0 5433.8 5476.2 5480.5 6126.3 6222.8 6238.1 6245.9 6252.9 6725.8 6732.6 6738.2 6746.3 6777.9 6625.3 6687.9 6700.8 6706.8 6824.3 6266.6 6296.5 6339.9 6345.9 6354.1 5972.5 6839.3 6878.8 6967.9 7001.4 6384.7 6585.6 6604.1 6629.9 6639.9 5744.7 6619.1 6642.5 6649.4 6766.0 5611.4 6622.3 6668.9 6697.9 6732.6 5745.4 6519.2 6633.2 6730.5 6756.8 5356.1 5369.7 5370.0 5375.8 5376.1 4630.3 4670.0 4675.4 4684.3 4707.9 6247.9 6266.7 6342.8 6377.9 6406.9 6109.9 6419.5 6752.9 6830.9 6867.0 5184.0 5834.8 6370.6 6377.6 6389.7 5583.4 5753.1 6008.2 6012.3 6059.3 5306.7 6912.7 6983.2 6996.9 6999.3 6717.6 6766.2 6805.3 6840.3 6841.2 6950.8 6953.1 6961.4 6975.5 6983.6 6659.5 6685.6 6686.7 6687.7 6715.9 6615.8 6715.1 6854.1 6854.7 6862.4 6490.2 6505.1 6540.6 6642.4 6652.8 6523.0 6621.1 6631.8 6635.9 6640.7 6424.7 6483.1 6495.6 6500.2 6515.8 5631.3 6461.4 6478.6 6565.4 6609.8 6288.2 6301.1 6301.3 6322.4 6348.0 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | [#1] ext.html Cycles:180 Average:43372.15 Median:34233.98 stddev:71651.77 (209.3%) stddev-sans-first:71781.30 07:32:02 INFO - PID 2919 | Values: 746.6 768.4 807.4 815.1 824.1 33827.7 38510.6 39291.4 39631.8 39683.8 32531.0 39047.8 39444.0 39544.8 40154.6 8360.2 8419.6 8459.3 8501.7 8503.7 7124.3 8276.1 8416.7 8424.0 8461.5 7710.3 8418.0 8452.6 8460.4 8473.3 7228.3 7810.8 8383.4 8449.4 8458.4 8247.2 8263.0 8298.8 8309.4 8314.6 37576.1 37815.0 37911.0 37914.4 37923.6 3562.0 3569.3 3615.9 3617.4 3631.7 30461.2 30467.8 30470.9 30474.0 30535.2 3024.7 3077.0 3098.4 3147.0 3147.9 233722.0 235784.0 235804.0 293437.0 297872.0 38925.8 47777.3 47790.8 47812.3 48465.3 51314.0 51323.7 51459.0 51475.0 51546.0 14249.4 14311.7 14318.0 14319.3 14409.0 9452.2 11225.0 11245.0 11388.9 11402.1 13906.5 13998.7 14000.9 14015.6 14136.5 5278.9 5898.8 5974.5 6037.1 6071.7 1002.6 1020.8 1024.0 1072.3 1078.5 23637.6 30746.2 31094.8 31446.2 31631.2 1106.1 1154.0 1156.0 1159.2 1169.2 10275.9 12654.0 12712.2 12738.6 12779.5 266553.0 267004.0 316965.0 318675.0 318693.0 51829.0 52070.0 52165.0 52169.2 52293.5 1413.7 1501.7 1502.8 1510.4 1539.0 22321.6 23118.0 24472.4 28183.7 30153.4 52525.7 53549.7 53685.9 53762.5 53818.7 237683.0 239588.0 239912.0 288787.0 291690.0 34640.3 34938.8 35264.3 35393.0 35490.3 42223.0 42731.1 43467.7 43554.3 44502.7 28907.6 29619.9 30181.7 30945.1 31729.8 30337.4 30883.1 31135.8 31204.0 31712.9 35760.0 37292.8 38060.0 38060.2 38121.0 29936.0 30564.1 30918.9 31280.1 32341.4 18790.5 24713.8 24946.3 25203.9 25296.9 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | [#2] jquery.html Cycles:180 Average:39661.74 Median:2237.72 stddev:101596.79 (4540.2%) stddev-sans-first:101839.23 07:32:02 INFO - PID 2919 | Values: 496.0 496.1 498.8 511.2 523.7 20743.8 21869.3 21975.2 22619.4 22734.5 22819.5 23344.0 23424.9 23572.3 23639.8 6065.8 6234.3 6279.0 6299.5 6311.4 6089.3 6204.5 6221.2 6227.8 6234.0 5980.4 6237.4 6280.1 6282.5 6295.0 6213.8 6249.5 6275.2 6275.3 6281.6 5699.8 5702.0 5706.1 5707.2 5740.2 27194.0 28975.7 29091.8 29201.9 29407.4 2209.2 2244.8 2247.0 2266.9 2267.4 17757.3 18029.9 18203.7 18208.0 18214.9 2159.4 2200.2 2230.7 2253.7 2260.5 160841.0 164380.7 166728.0 168274.0 184467.0 13094.2 13160.9 13568.5 14063.2 14684.5 16420.8 19302.5 19311.4 19361.6 19397.4 9439.2 9454.8 9489.5 9594.7 9782.5 7836.9 8566.0 8968.5 8981.2 9065.7 7394.1 7427.5 8422.9 8939.5 8948.4 2444.8 2552.4 2564.9 2569.6 2704.5 817.7 940.9 953.9 1014.4 1015.5 19762.3 20052.9 20972.9 24392.8 27050.7 1026.0 1041.9 1063.2 1070.6 1071.4 10824.6 11232.9 11241.2 11293.1 11349.5 588257.0 588377.0 588451.0 603460.0 604401.0 155665.2 158615.2 159276.0 160619.0 164500.0 1062.5 1208.9 1256.5 1268.3 1290.3 19246.7 21537.7 21732.9 21899.5 23168.0 54063.7 66325.0 69285.3 69312.0 69544.0 89389.1 89967.2 90998.0 92711.6 93668.0 11175.5 11278.0 11308.6 11351.4 11360.0 17019.0 17920.4 18426.9 18499.2 18571.0 14061.4 15104.2 15116.5 15231.8 15235.2 13271.7 13272.8 13424.7 13547.6 13778.0 15371.1 15432.7 15566.6 15569.0 15773.5 11525.7 11621.6 11625.0 11642.0 11655.8 14831.1 14881.3 14895.1 14917.3 14918.1 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | [#3] mootools.html Cycles:180 Average:8151.82 Median:2767.81 stddev:5433.58 (196.3%) stddev-sans-first:5424.71 07:32:02 INFO - PID 2919 | Values: 1339.2 1343.9 1373.3 1423.0 1449.3 13392.3 13666.8 13740.5 13783.6 13825.0 11735.9 12828.3 13349.7 13774.2 13869.5 2725.5 2753.6 2767.3 2777.9 2779.6 2689.3 2750.1 2752.1 2752.3 2755.0 2285.5 2688.8 2736.9 2740.5 2745.8 2324.0 2365.0 2383.4 2610.3 2766.3 2750.4 2761.1 2768.3 2769.0 2770.5 13457.8 13517.9 13580.2 13582.8 13620.5 7372.6 7675.3 7685.7 7692.4 7695.3 4504.6 4695.9 4804.0 4827.3 4834.9 1188.2 1364.9 1393.5 1414.5 1416.3 19920.5 19930.4 19969.8 20102.7 20126.2 6468.5 6481.2 6482.3 6492.4 6637.8 14157.0 14160.2 14167.6 14175.1 14225.8 7241.2 7376.7 7395.0 7402.3 7406.0 6594.3 6794.9 6819.4 6852.0 6861.6 4933.4 5012.0 5060.8 5072.8 5166.7 1450.4 1454.3 1467.8 1471.3 1483.9 801.9 812.0 822.7 837.2 857.6 7119.8 7372.3 7393.6 7789.0 8011.5 672.1 682.4 703.8 704.6 706.6 4178.6 4277.0 4680.6 5028.1 5232.2 15839.3 16885.0 17769.6 18509.0 18511.9 17899.0 17989.6 18183.7 18215.5 18593.9 9271.2 9356.2 9399.7 9421.0 9422.5 7193.6 7283.2 7795.8 7854.5 8034.2 15496.2 15553.2 16324.6 16373.3 16404.8 16474.3 16542.3 16834.3 16871.2 16884.6 8343.7 9740.7 9790.7 9906.5 9928.3 12028.8 12296.2 12456.6 12568.1 12628.9 8520.0 9834.9 10064.1 10216.9 10445.9 9518.9 9726.9 10084.0 10244.3 10335.5 9071.8 9131.9 9140.7 9143.7 9149.2 6060.5 6189.6 6226.7 6265.5 6468.9 6758.5 6851.7 7077.4 7093.0 7146.9 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | [#4] prototype.html Cycles:180 Average:5587.98 Median:6032.49 stddev:1680.85 (27.9%) stddev-sans-first:1640.41 07:32:02 INFO - PID 2919 | Values: 431.9 449.1 454.4 467.0 469.4 6030.3 6034.7 6278.3 6532.6 6573.7 4971.7 5547.7 6299.6 6366.4 6438.2 5036.5 5046.6 5049.6 5071.5 5081.2 5077.0 5079.6 5085.3 5089.3 5089.9 4362.0 4389.0 5035.4 5047.3 5084.1 4273.9 4600.7 4907.6 4926.9 5018.3 3946.7 3975.7 4550.4 4555.4 4569.2 5640.6 5804.4 6271.6 6295.9 6319.3 5982.9 5983.0 6001.2 6006.9 6018.1 6946.9 6949.9 6959.9 6960.6 6960.8 5199.0 5206.9 5224.7 5233.9 5235.1 6362.2 7232.6 7274.4 7285.6 7300.7 4356.8 4980.5 5383.4 5424.7 5478.5 4140.6 4505.1 5424.8 5435.9 5486.3 5876.2 6020.6 6025.0 6027.5 6042.4 5765.5 6159.4 6580.7 6960.7 6974.7 1304.0 1624.4 1626.3 1712.2 1860.6 948.7 1123.2 1140.5 1158.0 1165.2 5410.6 5552.5 6389.4 6443.8 6492.6 6714.8 6933.5 7117.3 7132.1 7141.0 5249.4 5641.6 6504.9 6506.2 6520.5 3411.9 3672.7 3690.6 3702.8 3706.2 7234.6 7248.0 7272.0 7349.8 7366.5 5821.6 6410.4 7133.9 7247.5 7252.3 6457.6 6629.7 7001.6 7266.6 7282.7 6235.7 6336.6 6967.8 7046.4 7104.5 6246.0 6297.7 6985.9 7001.9 7009.7 7264.2 7281.4 7291.7 7294.2 7363.9 3772.9 4254.7 4468.5 4578.7 4825.4 6599.8 7108.7 7159.5 7164.6 7171.6 5373.6 5725.4 6298.7 6857.3 6890.6 5976.5 6730.9 6742.4 6779.9 6796.7 5676.5 6043.8 6672.9 6698.7 6731.5 5945.6 6281.2 6334.2 6726.6 6745.9 5588.9 5838.8 6645.8 6650.7 6678.9 07:32:02 INFO - PID 2919 | 07:32:02 INFO - PID 2919 | [#5] yui.html Cycles:180 Average:25708.12 Median:17969.14 stddev:38617.74 (214.9%) stddev-sans-first:38680.64 07:32:02 INFO - PID 2919 | Values: 758.5 782.2 822.1 862.6 868.3 16820.4 16961.4 17303.3 17350.3 17384.5 10625.9 13345.8 13503.5 16167.4 16833.6 980.9 989.1 989.3 998.8 1000.2 991.7 993.6 994.5 995.5 996.1 964.3 1047.5 1047.6 1048.2 1050.1 1000.5 1069.7 1077.0 1078.6 1083.7 789.5 794.3 794.3 794.4 798.1 17425.8 17743.3 17923.4 17961.5 18140.8 17426.8 17640.9 17778.6 17785.4 17976.8 14822.6 14831.9 14836.4 15028.6 15029.8 3578.9 3650.9 3668.3 3677.3 3747.2 139306.3 140879.3 142352.3 143251.3 148648.0 13558.7 13972.9 13981.2 14003.6 14171.6 26394.6 26575.3 27059.5 27071.5 27087.6 7780.6 7795.0 7845.4 7852.3 7852.3 3917.7 3977.0 3981.8 3989.1 3997.3 4207.6 4319.2 4597.5 4606.1 4652.2 807.0 946.9 956.9 960.9 961.4 545.6 592.0 652.2 659.9 683.9 9954.8 11695.8 11977.0 12094.8 12303.6 618.9 657.4 664.4 683.5 685.8 5428.5 5630.1 5939.2 6007.5 6595.9 131068.0 131542.3 132677.0 134373.0 135278.0 113936.0 116478.0 118891.8 119479.4 127693.0 102627.5 103148.5 103275.0 103694.0 111068.4 12085.3 12439.6 12441.9 12561.2 12965.1 45692.4 46262.8 46826.0 47982.5 47985.0 78667.6 82516.8 84203.6 84638.0 87613.1 16950.7 17301.0 17616.5 17948.0 17956.4 18513.2 19017.3 19024.7 19161.5 19276.5 17119.9 17139.0 17901.0 17910.6 18042.1 17049.4 17940.0 18098.0 18108.5 18286.5 16596.7 17619.5 17860.4 18042.5 18270.6 10877.4 11679.9 11708.0 12274.0 12523.3 12948.1 12992.2 13093.3 13114.5 13567.8 07:32:02 INFO - PID 2919 | -------- Summary: end -------- 07:32:02 INFO - PID 2919 | 07:32:03 INFO - TEST-INFO | 2919: exit 0 07:32:03 INFO - TEST-OK | dromaeo_css | took 1144490ms 07:32:03 INFO - TEST-START | kraken 07:32:03 INFO - operating with platform_type : mac_ 07:32:03 INFO - Initialising browser for kraken test... 07:32:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:65011/getInfo.html -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpvCeznt/profile 07:32:03 INFO - TEST-INFO | started process 2926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:65011/getInfo.html -foreground) 07:32:36 INFO - TEST-INFO | 2926: killed by SIGTERM 07:32:36 INFO - Browser initialized. 07:32:36 INFO - Running cycle 1/1 for kraken test... 07:32:36 INFO - TEST-INFO | started process 2930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpvCeznt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:38 INFO - PID 2930 | 2017-06-14 07:32:38.652 plugin-container[2931:46311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 07:32:38 INFO - PID 2930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:38 INFO - PID 2930 | 2017-06-14 07:32:38.656 plugin-container[2931:46311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 07:32:38 INFO - PID 2930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:38 INFO - PID 2930 | 2017-06-14 07:32:38.656 plugin-container[2931:46311] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:32:39 INFO - PID 2930 | 2017-06-14 07:32:39.440 plugin-container[2932:46359] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 07:32:39 INFO - PID 2930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:39 INFO - PID 2930 | 2017-06-14 07:32:39.441 plugin-container[2932:46359] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971b, name = 'com.apple.CFPasteboardClient' 07:32:39 INFO - PID 2930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:39 INFO - PID 2930 | 2017-06-14 07:32:39.441 plugin-container[2932:46359] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:32:48 INFO - PID 2930 | RSS: Main: 254275584 07:32:48 INFO - PID 2930 | 07:32:51 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:65011/tests/kraken/driver.html?testName=audio-beat-detection) 07:32:52 INFO - PID 2930 | RSS: Main: 266366976 07:32:52 INFO - PID 2930 | 07:32:55 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:65011/tests/kraken/driver.html?testName=audio-dft) 07:32:55 INFO - PID 2930 | RSS: Main: 277594112 07:32:55 INFO - PID 2930 | 07:32:59 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:65011/tests/kraken/driver.html?testName=audio-fft) 07:32:59 INFO - PID 2930 | RSS: Main: 280301568 07:32:59 INFO - PID 2930 | 07:33:01 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:65011/tests/kraken/driver.html?testName=audio-oscillator) 07:33:02 INFO - PID 2930 | RSS: Main: 284676096 07:33:02 INFO - PID 2930 | 07:33:04 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:65011/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:33:04 INFO - PID 2930 | RSS: Main: 285192192 07:33:04 INFO - PID 2930 | 07:33:07 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:65011/tests/kraken/driver.html?testName=imaging-darkroom) 07:33:08 INFO - PID 2930 | RSS: Main: 290758656 07:33:08 INFO - PID 2930 | 07:33:11 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:65011/tests/kraken/driver.html?testName=imaging-desaturate) 07:33:12 INFO - PID 2930 | RSS: Main: 292769792 07:33:12 INFO - PID 2930 | 07:33:15 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:65011/tests/kraken/driver.html?testName=json-parse-financial) 07:33:16 INFO - PID 2930 | RSS: Main: 291078144 07:33:16 INFO - PID 2930 | 07:33:17 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:65011/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:33:18 INFO - PID 2930 | RSS: Main: 291139584 07:33:18 INFO - PID 2930 | 07:33:20 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:33:20 INFO - PID 2930 | RSS: Main: 290992128 07:33:20 INFO - PID 2930 | 07:33:22 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:33:23 INFO - PID 2930 | RSS: Main: 290918400 07:33:23 INFO - PID 2930 | 07:33:26 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:33:26 INFO - PID 2930 | RSS: Main: 290693120 07:33:26 INFO - PID 2930 | 07:33:30 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:33:30 INFO - PID 2930 | RSS: Main: 290660352 07:33:30 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | Cycle 1(1): loaded http://localhost:65011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:65011/tests/kraken/driver.html?testName=ai-astar) 07:33:32 INFO - PID 2930 | RSS: Main: 292376576 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | __start_tp_report 07:33:32 INFO - PID 2930 | _x_x_mozilla_page_load 07:33:32 INFO - PID 2930 | _x_x_mozilla_page_load_details 07:33:32 INFO - PID 2930 | |i|pagename|runs| 07:33:32 INFO - PID 2930 | |0;ai-astar;104;117;115;123;134;172;164;114;121;121 07:33:32 INFO - PID 2930 | |1;audio-beat-detection;148;111;142;139;146;124;111;150;149;114 07:33:32 INFO - PID 2930 | |2;audio-dft;208;202;209;265;211;239;192;201;198;197 07:33:32 INFO - PID 2930 | |3;audio-fft;68;81;74;130;81;90;62;71;106;63 07:33:32 INFO - PID 2930 | |4;audio-oscillator;68;71;113;73;71;75;117;68;73;113 07:33:32 INFO - PID 2930 | |5;imaging-gaussian-blur;90;89;91;108;89;89;90;94;112;86 07:33:32 INFO - PID 2930 | |6;imaging-darkroom;115;154;97;99;97;96;98;98;97;99 07:33:32 INFO - PID 2930 | |7;imaging-desaturate;147;92;96;92;112;143;93;87;95;92 07:33:32 INFO - PID 2930 | |8;json-parse-financial;51;50;50;63;50;53;52;51;53;49 07:33:32 INFO - PID 2930 | |9;json-stringify-tinderbox;58;54;57;59;65;67;71;79;61;60 07:33:32 INFO - PID 2930 | |10;stanford-crypto-aes;79;79;82;82;82;82;82;85;80;84 07:33:32 INFO - PID 2930 | |11;stanford-crypto-ccm;138;136;132;142;126;142;142;144;159;159 07:33:32 INFO - PID 2930 | |12;stanford-crypto-pbkdf2;184;182;189;195;246;185;230;192;183;181 07:33:32 INFO - PID 2930 | |13;stanford-crypto-sha256-iterative;62;92;98;92;66;68;68;67;62;65 07:33:32 INFO - PID 2930 | __end_tp_report 07:33:32 INFO - PID 2930 | __start_cc_report 07:33:32 INFO - PID 2930 | _x_x_mozilla_cycle_collect,1170 07:33:32 INFO - PID 2930 | __end_cc_report 07:33:32 INFO - PID 2930 | __startTimestamp1497450812733__endTimestamp 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | ------- Summary: start ------- 07:33:32 INFO - PID 2930 | Number of tests: 14 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#0] ai-astar Cycles:10 Average:128.50 Median:122.00 stddev:22.24 (18.2%) stddev-sans-first:21.75 07:33:32 INFO - PID 2930 | Values: 104.0 117.0 115.0 123.0 134.0 172.0 164.0 114.0 121.0 121.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:144.00 stddev:16.55 (11.5%) stddev-sans-first:16.69 07:33:32 INFO - PID 2930 | Values: 148.0 111.0 142.0 139.0 146.0 124.0 111.0 150.0 149.0 114.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#2] audio-dft Cycles:10 Average:212.20 Median:208.50 stddev:22.62 (10.8%) stddev-sans-first:23.94 07:33:32 INFO - PID 2930 | Values: 208.0 202.0 209.0 265.0 211.0 239.0 192.0 201.0 198.0 197.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#3] audio-fft Cycles:10 Average:82.60 Median:72.50 stddev:21.30 (29.4%) stddev-sans-first:21.93 07:33:32 INFO - PID 2930 | Values: 68.0 81.0 74.0 130.0 81.0 90.0 62.0 71.0 106.0 63.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:71.00 stddev:20.93 (29.5%) stddev-sans-first:21.37 07:33:32 INFO - PID 2930 | Values: 68.0 71.0 113.0 73.0 71.0 75.0 117.0 68.0 73.0 113.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:89.50 stddev:8.82 (9.9%) stddev-sans-first:9.24 07:33:32 INFO - PID 2930 | Values: 90.0 89.0 91.0 108.0 89.0 89.0 90.0 94.0 112.0 86.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:97.50 stddev:18.09 (18.5%) stddev-sans-first:18.82 07:33:32 INFO - PID 2930 | Values: 115.0 154.0 97.0 99.0 97.0 96.0 98.0 98.0 97.0 99.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#7] imaging-desaturate Cycles:10 Average:104.90 Median:92.00 stddev:22.14 (24.1%) stddev-sans-first:17.48 07:33:32 INFO - PID 2930 | Values: 147.0 92.0 96.0 92.0 112.0 143.0 93.0 87.0 95.0 92.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:51.50 stddev:4.02 (7.8%) stddev-sans-first:4.24 07:33:32 INFO - PID 2930 | Values: 51.0 50.0 50.0 63.0 50.0 53.0 52.0 51.0 53.0 49.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:7.53 (12.0%) stddev-sans-first:7.76 07:33:32 INFO - PID 2930 | Values: 58.0 54.0 57.0 59.0 65.0 67.0 71.0 79.0 61.0 60.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.00 stddev:1.95 (2.4%) stddev-sans-first:1.80 07:33:32 INFO - PID 2930 | Values: 79.0 79.0 82.0 82.0 82.0 82.0 82.0 85.0 80.0 84.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:142.00 stddev:10.49 (7.4%) stddev-sans-first:11.02 07:33:32 INFO - PID 2930 | Values: 138.0 136.0 132.0 142.0 126.0 142.0 142.0 144.0 159.0 159.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.70 Median:190.50 stddev:22.54 (11.8%) stddev-sans-first:23.43 07:33:32 INFO - PID 2930 | Values: 184.0 182.0 189.0 195.0 246.0 185.0 230.0 192.0 183.0 181.0 07:33:32 INFO - PID 2930 | 07:33:32 INFO - PID 2930 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:68.00 stddev:14.06 (20.7%) stddev-sans-first:14.22 07:33:32 INFO - PID 2930 | Values: 62.0 92.0 98.0 92.0 66.0 68.0 68.0 67.0 62.0 65.0 07:33:32 INFO - PID 2930 | -------- Summary: end -------- 07:33:32 INFO - PID 2930 | 07:33:33 INFO - TEST-INFO | 2930: exit 0 07:33:33 INFO - TEST-OK | kraken | took 90340ms 07:33:33 INFO - SUITE-END | took 1234s 07:33:33 INFO - Completed test suite (00:20:35) 07:33:33 INFO - javascript benchmark 07:33:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8321.960548356821, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3419.9911026247255, 3456.2741824216914, 3502.667246611572, 3558.6441355864417, 3591.138126849555, 6171.4445699887, 6171.475424588915, 6176.227971503562, 6197.318294987554, 6209.254889413265, 6149.893019256532, 6180.969095154529, 6193.690315484219, 6195.752169913209, 6260.373962603738, 5406.972965135169, 5422.186671999212, 5461.726913654321, 5483.369412517552, 5573.773769770664, 5501.174823776446, 5531.308586426697, 5538.947599955995, 5548.472895074961, 5560.7219639018085, 4561.977190114061, 5421.21392398104, 5440.619156659035, 5464.12573988162, 5482.91775623366, 4394.384786129945, 4431.468223813131, 5365.651232669864, 5421.0784166691365, 5457.290552228224, 5362.302900622894, 5393.0, 5433.755481003364, 5476.151196564539, 5480.534154596864, 6126.29547602027, 6222.813315600494, 6238.064290356438, 6245.906311405332, 6252.87494250117, 6725.831854203684, 6732.596044237362, 6738.22510411304, 6746.325367463214, 6777.8644427111185, 6625.271220165778, 6687.899681504781, 6700.83247918801, 6706.798796036127, 6824.317568243186, 6266.561340706198, 6296.464800491964, 6339.904901426528, 6345.904811427832, 6354.078658594571, 5972.462478376967, 6839.281875403011, 6878.793636190822, 6967.895481567679, 7001.36987671112, 6384.744610215539, 6585.5719378240265, 6604.1414616100565, 6629.900551491732, 6639.933600663931, 5744.684042377626, 6619.073329733841, 6642.501812363996, 6649.368310010541, 6766.018927255521, 5611.438856114356, 6622.337766223339, 6668.86662266752, 6697.8995315070315, 6732.562383445158, 5745.368009518949, 6519.152510173742, 6633.170853643294, 6730.461563074942, 6756.831079223059, 5356.062689029404, 5369.6509726867625, 5369.973150134224, 5375.838724838261, 5376.059189641797, 4630.32860235264, 4669.95330046702, 4675.438947326343, 4684.250519916865, 4707.929381059287, 6247.9062814056915, 6266.655333956676, 6342.778002769881, 6377.936220637827, 6406.903896441557, 6109.877802444015, 6419.45434638061, 6752.898706519406, 6830.93169068303, 6866.965665171642, 5184.0, 5834.795782147605, 6370.617762934146, 6377.649229292433, 6389.712462939179, 5583.3858275589655, 5753.05074662676, 6008.1588577598295, 6012.338642749294, 6059.333473317932, 5306.681599104143, 6912.723491060502, 6983.1620205575655, 6996.895046574407, 6999.335063168991, 6717.56335838169, 6766.15423072116, 6805.285445028201, 6840.281770414035, 6841.213260475109, 6950.756723514653, 6953.061336719478, 6961.443084553122, 6975.476839236976, 6983.615901125487, 6659.53383263167, 6685.565438246498, 6686.665666716547, 6687.732490700318, 6715.865682686416, 6615.801525954229, 6715.05989161508, 6854.074699915447, 6854.725810967506, 6862.382385585322, 6490.221173459215, 6505.121808555785, 6540.640264785482, 6642.4021838034805, 6652.767153149616, 6523.0, 6621.1392518972225, 6631.767888123893, 6635.9004614929845, 6640.667966601747, 6424.678766061585, 6483.09236706852, 6495.57778744399, 6500.187476565429, 6515.771947981798, 5631.267935168566, 6461.386168314003, 6478.643674597943, 6565.409113179837, 6609.801705948829, 6288.245410550763, 6301.149344838388, 6301.275353334242, 6322.430981211713, 6348.047792830927], "value": 6081.422211402874, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.5520687587448, 768.4083255892962, 807.4186585658324, 815.0586073085589, 824.1346586084611, 33827.66172338276, 38510.61489385105, 39291.41062884059, 39631.801840990825, 39683.80158099209, 32531.0, 39047.804760976156, 39444.0, 39544.813655590304, 40154.59845401545, 8360.205780450848, 8419.621117049748, 8459.323254139685, 8501.74494765155, 8503.65985360588, 7124.323189297009, 8276.131006244317, 8416.705415310465, 8424.0, 8461.450005749637, 7710.344620707247, 8418.03192632849, 8452.577371131449, 8460.407771456, 8473.279771219453, 7228.277172282782, 7810.843783124335, 8383.371247156458, 8449.408541402105, 8458.365622578269, 8247.21651443112, 8263.008438987299, 8298.751037468886, 8309.418340716153, 8314.625841837133, 37576.06059848498, 37815.0, 37911.0, 37914.43128353077, 37923.62076379256, 3562.020444377817, 3569.26829999852, 3615.9096022599647, 3617.439296908983, 3631.673149416565, 30461.23846903823, 30467.847660761778, 30470.933517326786, 30474.0, 30535.236619084477, 3024.74289685377, 3077.0, 3098.395812816502, 3147.0244224290545, 3147.9370412591848, 233721.9999999983, 235784.0, 235804.0, 293437.0, 297872.0, 38925.805370972965, 47777.28334074992, 47790.761046194544, 47812.28281575779, 48465.273020904715, 51314.0, 51323.74338128285, 51459.0, 51475.0, 51546.0, 14249.430022798972, 14311.713765724628, 14318.0, 14319.284035798168, 14409.0, 9452.196563292197, 11225.0, 11245.044171245372, 11388.94305528467, 11402.087832973342, 13906.51327203543, 13998.650033749034, 14000.859991399955, 14015.649608759863, 14136.50522231717, 5278.868028299324, 5898.793542226002, 5974.492168165754, 6037.124616930609, 6071.726772295169, 1002.6290272599039, 1020.8213562626511, 1024.0015984415136, 1072.292287090532, 1078.5254488025244, 23637.645435318485, 30746.231344216125, 31094.844525777225, 31446.213844654067, 31631.20921976923, 1106.115107913666, 1153.988460115405, 1155.9768804623861, 1159.2175281684829, 1169.1990986174328, 10275.897241027644, 12654.0, 12712.237265764084, 12738.55415060487, 12779.488820447265, 266553.0, 267004.0, 316965.0, 318675.0, 318693.0, 51829.0, 52070.0, 52165.0, 52169.2174617381, 52293.47706522886, 1413.738458385202, 1501.737195990719, 1502.8497150285102, 1510.4033906606828, 1538.9769153462482, 22321.553568928204, 23118.0, 24472.388190295384, 28183.71816281811, 30153.396932061674, 52525.68685782886, 53549.73225133849, 53685.92628147493, 53762.46237537575, 53818.73090634522, 237683.0, 239588.0, 239912.0, 288787.0, 291690.0, 34640.30719385648, 34938.82530587432, 35264.29471410506, 35393.0, 35490.29019419649, 42223.0, 42731.14537709291, 43467.69596912098, 43554.25782968646, 44502.66492005245, 28907.566386503797, 29619.861635647234, 30181.69818301789, 30945.0716478506, 31729.8413507931, 30337.393252135276, 30883.0735077948, 31135.84432077825, 31204.0, 31712.8900488491, 35760.0, 37292.813535932146, 38060.04849878775, 38060.2387952245, 38121.0, 29936.0, 30564.123072308554, 30918.923225542552, 31280.061598152093, 32341.35317293688, 18790.53023674419, 24713.752862471145, 24946.251612451655, 25203.873980630713, 25296.873515632305], "value": 17560.976002703508, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.9628027897908, 496.1391984906187, 498.76558017731674, 511.2484647568075, 523.6779380680878, 20743.792562074374, 21869.250459963205, 21975.23086691966, 22619.434514137134, 22734.545309093828, 22819.543609127806, 23344.0, 23424.88287558564, 23572.292831215047, 23639.763602364015, 6065.848353791157, 6234.345393733661, 6279.0, 6299.464545513614, 6311.400416960404, 6089.330173680892, 6204.534659900503, 6221.191245138126, 6227.750889964396, 6234.0337247726675, 5980.43185897339, 6237.376262373772, 6280.120783090371, 6282.528810339243, 6294.968525157367, 6213.782517611885, 6249.468795152417, 6275.215598050244, 6275.341089185616, 6281.591696539733, 5699.829005129853, 5702.0021496238, 5706.058500347479, 5707.229524014288, 5740.196372507852, 27194.0, 28975.710242897723, 29091.836326546912, 29201.85399072991, 29407.41185176306, 2209.171560664751, 2244.7867452591977, 2247.0, 2266.9232114745528, 2267.444476103365, 17757.28970841165, 18029.918204907746, 18203.726944095848, 18208.0, 18214.90892545542, 2159.3845753960045, 2200.19692812124, 2230.654248591471, 2253.6506841439605, 2260.480089579406, 160841.0, 164380.71238575282, 166728.0, 168274.0, 184467.0, 13094.214347139203, 13160.934195329153, 13568.525101621395, 14063.226522541154, 14684.486042988445, 16420.83579164217, 19302.517437063903, 19311.42065738002, 19361.61276774485, 19397.4180774577, 9439.150476457151, 9454.810903782023, 9489.525523723787, 9594.66418675343, 9782.461964591874, 7836.921630783733, 8565.95717021411, 8968.461892286483, 8981.236594889508, 9065.728028159167, 7394.112706475257, 7427.480076394709, 8422.883533092794, 8939.541626052758, 8948.373613847098, 2444.7799698026906, 2552.3746682063015, 2564.910228142006, 2569.5760199567235, 2704.5402281612173, 817.721974528663, 940.9435433873989, 953.8998405167496, 1014.3558840136567, 1015.4770293298957, 19762.308319209045, 20052.897090660153, 20972.895135524224, 24392.75607243905, 27050.72949270482, 1025.9538320775582, 1041.8695715149147, 1063.2079101069753, 1070.6038765656756, 1071.3893080943787, 10824.56701731922, 11232.887671123348, 11241.21311508203, 11293.096552275798, 11349.542228830953, 588257.0, 588377.0, 588451.0, 603460.0, 604401.0, 155665.22167389316, 158615.20692396694, 159276.0, 160619.0, 164500.0, 1062.490004797686, 1208.8972437342925, 1256.4911210959576, 1268.2834198677717, 1290.270996886756, 19246.7112993308, 21537.7077375358, 21732.913354332537, 21899.452513687283, 23168.0, 54063.72968135292, 66325.0, 69285.30714692788, 69312.0, 69544.0, 89389.10610893808, 89967.2006559852, 90998.0, 92711.60932586151, 93668.0, 11175.49710263024, 11278.0, 11308.604198853001, 11351.37567433799, 11359.977602015528, 17019.0, 17920.372786952394, 18426.907865461122, 18499.167537460577, 18571.0, 14061.382940961512, 15104.244787760788, 15116.54650360491, 15231.847681523042, 15235.162066086028, 13271.66820829487, 13272.800907986195, 13424.731505370033, 13547.59357219285, 13777.966652500902, 15371.077735335468, 15432.691346172789, 15566.610834729223, 15569.0, 15773.526794196192, 11525.654230373102, 11621.593244236412, 11625.0, 11642.01042911362, 11655.825162622397, 14831.11013339203, 14881.330340134937, 14895.106293622417, 14917.328720207399, 14918.104913705212], "value": 11557.786965744242, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.236635117983, 1343.892488600912, 1373.2858913365048, 1423.0181174989264, 1449.2608769527537, 13392.263425511594, 13666.794998075024, 13740.450381984734, 13783.586492405244, 13824.96312776542, 11735.941320293408, 12828.294443805611, 13349.733005339887, 13774.173549587033, 13869.514566990158, 2725.4685336359366, 2753.5869619557025, 2767.3220061085062, 2777.9305517362027, 2779.6247506586656, 2689.3007817967273, 2750.147454289177, 2752.1330780804083, 2752.325680208342, 2755.0, 2285.4514916420108, 2688.7714544263763, 2736.8905243790223, 2740.5067087924167, 2745.807793454459, 2323.9767602323927, 2365.0185173153122, 2383.380321116505, 2610.282172402576, 2766.280767000574, 2750.40866213765, 2761.0888406825698, 2768.307923019245, 2768.9723102768917, 2770.473610014091, 13457.798133027914, 13517.932410337886, 13580.185188888698, 13582.796258056138, 13620.523281685093, 7372.594507302097, 7675.270849269311, 7685.692572297102, 7692.384609231248, 7695.268949449794, 4504.594586487199, 4695.906081878377, 4803.951960480385, 4827.300041493963, 4834.8791280218275, 1188.233589334874, 1364.8976326775432, 1393.470481217131, 1414.455434657654, 1416.2706206303762, 19920.501987450283, 19930.402087937386, 19969.800301997086, 20102.698459522973, 20126.19495220204, 6468.482521398277, 6481.222253329536, 6482.254540727782, 6492.383223593751, 6637.767678131242, 14157.00900936945, 14160.150390976574, 14167.64580885486, 14175.149491030572, 14225.78661320081, 7241.203467618558, 7376.7049318026675, 7395.038644976226, 7402.259774022662, 7405.9629701851145, 6594.307597702268, 6794.932050679528, 6819.352161544645, 6852.0, 6861.588304701734, 4933.383327084114, 5012.0, 5060.822871199491, 5072.807890145813, 5166.689998600096, 1450.3980847948194, 1454.2583282525989, 1467.7945087687733, 1471.3231913319778, 1483.940642374293, 801.887735717, 811.9675212991532, 822.7494208802586, 837.2130197614223, 857.5540718826301, 7119.786406407816, 7372.336489715952, 7393.593352365564, 7789.0, 8011.519308841488, 672.122879642062, 682.4097155960158, 703.7853454696367, 704.6018999265399, 706.6325510734389, 4178.639788130844, 4277.0, 4680.625549955996, 5028.094942910311, 5232.1628539433505, 15839.336869628754, 16885.0, 17769.555761105818, 18509.0, 18511.90744046298, 17899.0, 17989.550261243312, 18183.727244091348, 18215.45353639393, 18593.90703046503, 9271.211946984447, 9356.204722598519, 9399.671011514563, 9421.0, 9422.481763503074, 7193.604351760703, 7283.2352602975925, 7795.766127016199, 7854.489458185429, 8034.196580341919, 15496.225188740744, 15553.222338883237, 16324.591885202964, 16373.263203156124, 16404.753928691316, 16474.258658360162, 16542.255598498337, 16834.326626934784, 16871.240794164045, 16884.551739997387, 8343.666253349798, 9740.707778766648, 9790.657326993522, 9906.455144967096, 9928.255380846322, 12028.819567706667, 12296.20074695142, 12456.564020259248, 12568.057395695543, 12628.87371126277, 8520.0, 9834.852477212704, 10064.094231519199, 10216.897831021595, 10445.895541044492, 9518.857217141887, 9726.902730972599, 10084.042016008467, 10244.334118282288, 10335.534900929326, 9071.818563628558, 9131.908680913371, 9140.680076197566, 9143.725688229364, 9149.222316103205, 6060.545459090497, 6189.6286222826775, 6226.719797609027, 6265.467435268053, 6468.8706225874275, 6758.4931130166415, 6851.657417129024, 7077.433805295461, 7092.964535177498, 7146.9285307146265], "value": 5974.192965292682, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.93520971854196, 449.133172976156, 454.3979227523531, 466.9929951050735, 469.4085452330063, 6030.276366835986, 6034.69826508675, 6278.34077421871, 6532.608043517393, 6573.73705051798, 4971.676841005332, 5547.72261386931, 6299.551103246256, 6366.363363663643, 6438.227412710469, 5036.471170527097, 5046.64673472858, 5049.570786483153, 5071.518205770446, 5081.187010078388, 5076.974615126919, 5079.644424890259, 5085.28805967163, 5089.312942752718, 5089.9100899100895, 4361.956380436187, 4388.956110438887, 5035.370578677665, 5047.293378926953, 5084.144287335133, 4273.87178384649, 4600.6549508786975, 4907.558319751204, 4926.852194434173, 5018.272350509193, 3946.6842652587725, 3975.6819454443576, 4550.431196100488, 4555.362249285103, 4569.154706379297, 5640.5769567282205, 5804.361520232771, 6271.648131950518, 6295.905561416583, 6319.33646967071, 5982.940170598282, 5983.012802887519, 6001.159837622693, 6006.909896351558, 6018.097285407181, 6946.895796563055, 6949.861002779967, 6959.930400695979, 6960.582365058062, 6960.756373526902, 5199.0, 5206.947930520684, 5224.712640804754, 5233.921491177635, 5235.110031294689, 6362.236531616236, 7232.60220687862, 7274.381677557414, 7285.635718214068, 7300.7444739434395, 4356.760378179167, 4980.452150263468, 5383.43473935239, 5424.728763561806, 5478.479544443271, 4140.60664236897, 4505.098980203972, 5424.833660762955, 5435.864103397446, 5486.286782718301, 5876.1794338717655, 6020.638761674313, 6024.969875150597, 6027.48766354856, 6042.425969532887, 5765.4522820332, 6159.353267906805, 6580.7496575650475, 6960.651967401711, 6974.721011159599, 1303.9739205216033, 1624.3746157729358, 1626.2600516764844, 1712.161041089857, 1860.5627677495777, 948.7390967483997, 1123.2081382625393, 1140.5152810055692, 1157.9536818527333, 1165.2425923149851, 5410.59420543453, 5552.5280351169795, 6389.361063893573, 6443.7744678936015, 6492.64290464029, 6714.83212919681, 6933.5493192942295, 7117.3238542338395, 7132.072830531998, 7141.036724617783, 5249.448807875118, 5641.63051518355, 6504.934950650528, 6506.186726659168, 6520.543561950618, 3411.880584179542, 3672.7245456590863, 3690.5755838078426, 3702.7963462009848, 3706.221693444353, 7234.5659260444545, 7247.963760181271, 7271.963640181765, 7349.7950614815345, 7366.484346095722, 5821.592488525763, 6410.410170304551, 7133.928660713326, 7247.4926755126635, 7252.311030452204, 6457.644829534421, 6629.740349333708, 7001.649917504104, 7266.600336981517, 7282.745103921443, 6235.719392627433, 6336.619802811846, 6967.825804354931, 7046.4010559101, 7104.467164962752, 6246.0, 6297.740451909728, 6985.895211571728, 7001.929980700128, 7009.684564194521, 7264.183954011497, 7281.417486601165, 7291.708331666886, 7294.161171465137, 7363.852722945404, 3772.850059668478, 4254.723442976197, 4468.530804265635, 4578.748168850745, 4825.420949486084, 6599.769008084694, 7108.680109394986, 7159.534630249017, 7164.570125792469, 7171.605561694157, 5373.570114390917, 5725.423559398204, 6298.748050077946, 6857.27998560144, 6890.552114112566, 5976.521878249817, 6730.8653826924165, 6742.42689371409, 6779.864402712017, 6796.694148763417, 5676.489115979582, 6043.758249669964, 6672.866542669217, 6698.66506674674, 6731.495137864582, 5945.583809133319, 6281.246250449975, 6334.239891213084, 6726.630035348103, 6745.865082698417, 5588.86027849307, 5838.795642152504, 6645.833854153685, 6650.700718467778, 6678.866422671616], "value": 5074.540239505435, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.4728613613537, 782.2295039386204, 822.0833770346065, 862.5988915154454, 868.2966796894516, 16820.411285605005, 16961.406350777725, 17303.30786768528, 17350.305987760476, 17384.478465646036, 10625.893741062606, 13345.799813002788, 13503.459861605525, 16167.43413980511, 16833.579160520992, 980.8626792249091, 989.0603926270054, 989.2778271861546, 998.8052329795693, 1000.2048371544603, 991.7471044883545, 993.5975929748463, 994.5375400438794, 995.5370752600039, 996.1482932093065, 964.3249725192359, 1047.455323231919, 1047.5547892145846, 1048.1667074675652, 1050.112654806687, 1000.5147503460815, 1069.7272195590074, 1077.0252921106412, 1078.6332646900053, 1083.7182332593543, 789.4947233770391, 794.2970471133032, 794.3287921706149, 794.3526026288614, 798.1340245833278, 17425.825741742672, 17743.29026838925, 17923.37268195607, 17961.461156165336, 18140.818591814044, 17426.825731742647, 17640.91179544094, 17778.555536111573, 17785.377511787156, 17976.820231797778, 14822.629434264121, 14831.92584037073, 14836.406543738238, 15028.624284392869, 15029.849701502955, 3578.910527236814, 3650.8539658413633, 3668.3396988542054, 3677.3380791457525, 3747.2318174774105, 139306.30346848202, 140879.29560352236, 142352.28823855813, 143251.28374358165, 148648.0, 13558.661033474242, 13972.860271397156, 13981.231032293119, 14003.579892603238, 14171.64570885736, 26394.6040809388, 26575.33561660974, 27059.458810823675, 27071.458570828472, 27087.593686094726, 7780.610969451618, 7794.961025194838, 7845.3723702104835, 7852.254035866584, 7852.293293603603, 3917.745346552465, 3977.025628720924, 3981.8009099544906, 3989.1024519482885, 3997.320455522568, 4207.558206388347, 4319.244132276839, 4597.540245975376, 4606.124836281163, 4652.185867473178, 806.9919300807035, 946.9195118415012, 956.9043445255282, 960.9471479068718, 961.4183419031525, 545.6344249352943, 592.0054308761237, 652.2433976587137, 659.9373059559334, 683.9281875403011, 9954.800903981879, 11695.766084678258, 11977.0, 12094.818577721342, 12303.630891073282, 618.913352130702, 657.3893959798513, 664.4153145232165, 683.5181197255922, 685.7565564224628, 5428.4842939920645, 5630.099184130601, 5939.168516407706, 6007.549433792483, 6595.934040659628, 131068.0, 131542.34228828794, 132677.0, 134373.0, 135278.0, 113936.0, 116478.0, 118891.8110818898, 119479.40260298643, 127693.0, 102627.48686256522, 103148.48425757974, 103275.0, 103694.0, 111068.44465777781, 12085.335306558223, 12439.626811195678, 12441.875581244434, 12561.246325220516, 12965.092443528862, 45692.400765973034, 46262.768686156356, 46826.0, 47982.520174797806, 47985.0, 78667.60666196632, 82516.76224856512, 84203.5789821047, 84638.0, 87613.1238687605, 16950.745738814174, 17300.961942283502, 17616.471505854846, 17948.0, 17956.371526996492, 18513.166907489463, 19017.33439329617, 19024.71462928085, 19161.52096197606, 19276.518087047934, 17119.93080914847, 17138.97166170034, 17901.015444150697, 17910.641787163924, 18042.09789510493, 17049.40327088546, 17940.013299268667, 18098.00460974659, 18108.547286317946, 18286.542836429195, 16596.668066638358, 17619.471415857544, 17860.374886878897, 18042.458726238234, 18270.634587308446, 10877.401742904216, 11679.883201167879, 11708.0, 12274.0, 12523.311217883103, 12948.09363344557, 12992.220466772023, 13093.345332733516, 13114.540991065267, 13567.79648305296], "value": 8876.089602082624, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 115.0, 123.0, 134.0, 172.0, 164.0, 114.0, 121.0, 121.0], "value": 128.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 142.0, 139.0, 146.0, 124.0, 111.0, 150.0, 149.0, 114.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 202.0, 209.0, 265.0, 211.0, 239.0, 192.0, 201.0, 198.0, 197.0], "value": 212.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 74.0, 130.0, 81.0, 90.0, 62.0, 71.0, 106.0, 63.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 113.0, 73.0, 71.0, 75.0, 117.0, 68.0, 73.0, 113.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 108.0, 89.0, 89.0, 90.0, 94.0, 112.0, 86.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 154.0, 97.0, 99.0, 97.0, 96.0, 98.0, 98.0, 97.0, 99.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 92.0, 96.0, 92.0, 112.0, 143.0, 93.0, 87.0, 95.0, 92.0], "value": 104.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 63.0, 50.0, 53.0, 52.0, 51.0, 53.0, 49.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 57.0, 59.0, 65.0, 67.0, 71.0, 79.0, 61.0, 60.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 82.0, 82.0, 82.0, 82.0, 82.0, 85.0, 80.0, 84.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 132.0, 142.0, 126.0, 142.0, 142.0, 144.0, 159.0, 159.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 189.0, 195.0, 246.0, 185.0, 230.0, 192.0, 183.0, 181.0], "value": 196.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 92.0, 98.0, 92.0, 66.0, 68.0, 68.0, 67.0, 62.0, 65.0], "value": 74.0, "unit": "score"}]}]} 07:33:34 INFO - Return code: 0 07:33:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:33:34 INFO - # TBPL SUCCESS # 07:33:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:34 INFO - 2017-06-14 07:33:34.134 screenresolution[2938:46731] starting screenresolution argv=screenresolution get 07:33:34 INFO - 2017-06-14 07:33:34.136 screenresolution[2938:46731] Display 0: 1600x1200x32@60 07:33:34 INFO - 2017-06-14 07:33:34.142 screenresolution[2939:46734] starting screenresolution argv=screenresolution list 07:33:34 INFO - Available Modes on Display 0 07:33:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:34 INFO - Intel Iris: 07:33:34 INFO - Chipset Model: Intel Iris 07:33:34 INFO - Type: GPU 07:33:34 INFO - Bus: Built-In 07:33:34 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:34 INFO - Vendor: Intel (0x8086) 07:33:34 INFO - Device ID: 0x0a2e 07:33:34 INFO - Revision ID: 0x0009 07:33:34 INFO - Displays: 07:33:34 INFO - Display: 07:33:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:34 INFO - Display Serial Number: 1600x1200 60 07:33:34 INFO - Main Display: Yes 07:33:34 INFO - Mirror: Off 07:33:34 INFO - Online: Yes 07:33:34 INFO - Rotation: Supported 07:33:34 INFO - Return code: 0 07:33:34 INFO - Running post-action listener: _resource_record_post_action 07:33:34 INFO - [mozharness: 2017-06-14 14:33:34.378787Z] Finished run-tests step (success) 07:33:34 INFO - Running post-run listener: _resource_record_post_run 07:33:34 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 0; Write bytes: 572997632; Read time: 0; Write time: 1806 07:33:34 INFO - TinderboxPrint: CPU usage
53.3% 07:33:34 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 07:33:34 INFO - TinderboxPrint: I/O write bytes / time
572,997,632 / 1,806 07:33:34 INFO - TinderboxPrint: CPU idle
2,348.1 (46.7%) 07:33:34 INFO - TinderboxPrint: CPU system
1,012.1 (20.1%) 07:33:34 INFO - TinderboxPrint: CPU user
1,669.5 (33.2%) 07:33:34 INFO - TinderboxPrint: Swap in / out
252,530,688 / 0 07:33:34 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252672512; Write bytes: 246908928; Read time: 18014; Write time: 540 07:33:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:33:34 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 0; Write bytes: 315811840; Read time: 0; Write time: 1223 07:33:34 INFO - Running post-run listener: _upload_blobber_files 07:33:34 INFO - Blob upload gear active. 07:33:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:33:34 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 07:33:34 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'] 07:33:34 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 07:33:35 INFO - (blobuploader) - INFO - Open directory for files ... 07:33:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:33:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:33:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:35 INFO - (blobuploader) - INFO - Done attempting. 07:33:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:33:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:33:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:36 INFO - (blobuploader) - INFO - Done attempting. 07:33:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:33:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:33:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:37 INFO - (blobuploader) - INFO - Done attempting. 07:33:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:33:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:33:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:40 INFO - (blobuploader) - INFO - Done attempting. 07:33:40 INFO - (blobuploader) - INFO - Iteration through files over. 07:33:40 INFO - Return code: 0 07:33:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:33:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:33:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9d040829095687c94facef86c7e8d8f4f66662b8e8aaa6f54a3054067c1b735a7ae76300dfeca8e60fa4553e39fb36a98940c074c3636036674a8a4a1079993", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6dab04d9171d821d415024aa255ecdc43069eb43012a31e15e0c96718845098a972fd626ed4f704c1912f4ae37288e6aaae44d82fe4daee7cab52716d6d2a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a1198b9bf57690467f6491560d00de5ccdf5ac656330f9606464c6d6bb1822a53e3028e2ad7817c7d0e0a3bb419b85930d47c2ea6441267e379faa3f7536e91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aab8b62bbf83f23c08b988dce30d49e2ba6ea26da7a0f702a9e342a750e49fa71731054bca58de6246df44361bf10295cae619c1dcc9317d1b1ea1c33fc3e6ee"} 07:33:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:33:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:33:40 INFO - Contents: 07:33:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9d040829095687c94facef86c7e8d8f4f66662b8e8aaa6f54a3054067c1b735a7ae76300dfeca8e60fa4553e39fb36a98940c074c3636036674a8a4a1079993", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6dab04d9171d821d415024aa255ecdc43069eb43012a31e15e0c96718845098a972fd626ed4f704c1912f4ae37288e6aaae44d82fe4daee7cab52716d6d2a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a1198b9bf57690467f6491560d00de5ccdf5ac656330f9606464c6d6bb1822a53e3028e2ad7817c7d0e0a3bb419b85930d47c2ea6441267e379faa3f7536e91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aab8b62bbf83f23c08b988dce30d49e2ba6ea26da7a0f702a9e342a750e49fa71731054bca58de6246df44361bf10295cae619c1dcc9317d1b1ea1c33fc3e6ee"} 07:33:40 INFO - Running post-run listener: copy_logs_to_upload_dir 07:33:40 INFO - Copying logs to upload dir... 07:33:40 INFO - mkdir: /builds/slave/test/build/upload/logs 07:33:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.398732 ========= master_lag: 0.47 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-14 07:33:40.135663) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 07:33:40.139710) ========= 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.d2s4Miuz97/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.hoqrDm4AGo/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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/d9d040829095687c94facef86c7e8d8f4f66662b8e8aaa6f54a3054067c1b735a7ae76300dfeca8e60fa4553e39fb36a98940c074c3636036674a8a4a1079993", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6dab04d9171d821d415024aa255ecdc43069eb43012a31e15e0c96718845098a972fd626ed4f704c1912f4ae37288e6aaae44d82fe4daee7cab52716d6d2a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a1198b9bf57690467f6491560d00de5ccdf5ac656330f9606464c6d6bb1822a53e3028e2ad7817c7d0e0a3bb419b85930d47c2ea6441267e379faa3f7536e91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aab8b62bbf83f23c08b988dce30d49e2ba6ea26da7a0f702a9e342a750e49fa71731054bca58de6246df44361bf10295cae619c1dcc9317d1b1ea1c33fc3e6ee"} build_url:https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011519 build_url: 'https://queue.taskcluster.net/v1/task/ASYh5tv9ScevUYqkXWa4gA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9d040829095687c94facef86c7e8d8f4f66662b8e8aaa6f54a3054067c1b735a7ae76300dfeca8e60fa4553e39fb36a98940c074c3636036674a8a4a1079993", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6dab04d9171d821d415024aa255ecdc43069eb43012a31e15e0c96718845098a972fd626ed4f704c1912f4ae37288e6aaae44d82fe4daee7cab52716d6d2a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a1198b9bf57690467f6491560d00de5ccdf5ac656330f9606464c6d6bb1822a53e3028e2ad7817c7d0e0a3bb419b85930d47c2ea6441267e379faa3f7536e91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aab8b62bbf83f23c08b988dce30d49e2ba6ea26da7a0f702a9e342a750e49fa71731054bca58de6246df44361bf10295cae619c1dcc9317d1b1ea1c33fc3e6ee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 07:33:40.173464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:33:40.173788) ========= 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.d2s4Miuz97/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.hoqrDm4AGo/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.005227 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 07:33:40.222424) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 07:33:40.222742) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 07:33:40.223124) ========= ========= Total master_lag: 0.61 =========