builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0161 starttime: 1497376259.06 results: success (0) revision: 6558a0d4e3e76b6115c1717f6d4dfb0fa58c4873 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.061697) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.062187) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.062478) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.085971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.086280) ========= 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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-13 10:50:59-- 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% 6.25M=0.002s 2017-06-13 10:50:59 (6.25 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124853 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.233385) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.233715) ========= 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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.022552 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:50:59.282598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 10:50:59.282940) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 6558a0d4e3e76b6115c1717f6d4dfb0fa58c4873 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev 6558a0d4e3e76b6115c1717f6d4dfb0fa58c4873 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-13 10:50:59,373 truncating revision to first 12 chars 2017-06-13 10:50:59,373 Setting DEBUG logging. 2017-06-13 10:50:59,373 attempt 1/10 2017-06-13 10:50:59,373 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/6558a0d4e3e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 10:51:00,832 unpacking tar archive at: cedar-6558a0d4e3e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.705800 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 10:51:01.010055) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 10:51:01.010380) ========= script_repo_revision: 6558a0d4e3e76b6115c1717f6d4dfb0fa58c4873 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 10:51:01.010769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 10:51:01.011055) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 10:51:01.029543) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-13 10:51:01.029871) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:51:01 INFO - MultiFileLogger online at 20170613 10:51:01 in /builds/slave/test 10:51:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 10:51:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:51:01 INFO - {'append_to_log': False, 10:51:01 INFO - 'base_work_dir': '/builds/slave/test', 10:51:01 INFO - 'blob_upload_branch': 'Cedar', 10:51:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:51:01 INFO - 'branch': 'Cedar', 10:51:01 INFO - 'buildbot_json_path': 'buildprops.json', 10:51:01 INFO - 'config_files': ('talos/mac_config.py',), 10:51:01 INFO - 'default_actions': ('clobber', 10:51:01 INFO - 'read-buildbot-config', 10:51:01 INFO - 'download-and-extract', 10:51:01 INFO - 'populate-webroot', 10:51:01 INFO - 'create-virtualenv', 10:51:01 INFO - 'install', 10:51:01 INFO - 'setup-mitmproxy', 10:51:01 INFO - 'run-tests'), 10:51:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:51:01 INFO - 'download_minidump_stackwalk': True, 10:51:01 INFO - 'download_symbols': 'ondemand', 10:51:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:51:01 INFO - 'tooltool.py': '/tools/tooltool.py', 10:51:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:51:01 INFO - '/tools/misc-python/virtualenv.py')}, 10:51:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:51:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:51:01 INFO - 'gecko_profile': False, 10:51:01 INFO - 'gecko_profile_interval': 0, 10:51:01 INFO - 'installer_path': 'installer.exe', 10:51:01 INFO - 'log_level': 'info', 10:51:01 INFO - 'log_name': 'talos', 10:51:01 INFO - 'log_to_console': True, 10:51:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:51:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:51:01 INFO - 'opt_config_files': (), 10:51:01 INFO - 'pip_index': False, 10:51:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:01 INFO - 'cmd': ('bash', 10:51:01 INFO - '-c', 10:51:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:01 INFO - 'enabled': True, 10:51:01 INFO - 'halt_on_failure': False, 10:51:01 INFO - 'name': 'check_screen_resolution'},), 10:51:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:01 INFO - 'cmd': ('bash', 10:51:01 INFO - '-c', 10:51:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:01 INFO - 'enabled': True, 10:51:01 INFO - 'halt_on_failure': False, 10:51:01 INFO - 'name': 'check_screen_resolution'},), 10:51:01 INFO - 'run_cmd_checks_enabled': True, 10:51:01 INFO - 'suite': 'dromaeojs-e10s', 10:51:01 INFO - 'system_bits': '32', 10:51:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:51:01 INFO - 'title': 't-yosemite-r7-0161', 10:51:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:51:01 INFO - 'use_talos_json': True, 10:51:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:51:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:51:01 INFO - 'work_dir': 'build'} 10:51:01 INFO - [mozharness: 2017-06-13 17:51:01.250237Z] Running clobber step. 10:51:01 INFO - Running pre-action listener: _resource_record_pre_action 10:51:01 INFO - Running main action method: clobber 10:51:01 INFO - rmtree: /builds/slave/test/build 10:51:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:51:02 INFO - Running post-action listener: _resource_record_post_action 10:51:02 INFO - [mozharness: 2017-06-13 17:51:02.004247Z] Finished clobber step (success) 10:51:02 INFO - [mozharness: 2017-06-13 17:51:02.004383Z] Running read-buildbot-config step. 10:51:02 INFO - Running pre-action listener: _resource_record_pre_action 10:51:02 INFO - Running main action method: read_buildbot_config 10:51:02 INFO - Using buildbot properties: 10:51:02 INFO - { 10:51:02 INFO - "project": "", 10:51:02 INFO - "product": "firefox", 10:51:02 INFO - "who": "mconley@mozilla.com", 10:51:02 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 10:51:02 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 10:51:02 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 10:51:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 10:51:02 INFO - "stage_platform": "macosx64", 10:51:02 INFO - "basedir": "/builds/slave/test", 10:51:02 INFO - "buildnumber": 3, 10:51:02 INFO - "platform": "yosemite_r7", 10:51:02 INFO - "parent_task_id": "Bw8MRetuSX-rlbh2Y5OAtQ", 10:51:02 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:51:02 INFO - "slavebuilddir": "test", 10:51:02 INFO - "taskId": "AEjl18f2RwKD65xh27VHyQ", 10:51:02 INFO - "branch": "cedar", 10:51:02 INFO - "script_repo_revision": "default", 10:51:02 INFO - "revision": "6558a0d4e3e76b6115c1717f6d4dfb0fa58c4873", 10:51:02 INFO - "slavename": "t-yosemite-r7-0161", 10:51:02 INFO - "repo_path": "projects/cedar" 10:51:02 INFO - } 10:51:02 INFO - Finding installer, test and symbols from parent task. 10:51:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AEjl18f2RwKD65xh27VHyQ'}, attempt #1 10:51:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AEjl18f2RwKD65xh27VHyQ'}, attempt #1 10:51:03 INFO - Task dependencies: fSn5vcGoSHu_hUkEE98NvA Bw8MRetuSX-rlbh2Y5OAtQ 10:51:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ'}, attempt #1 10:51:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 10:51:03 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/test_packages.json 10:51:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/target.crashreporter-symbols.zip 10:51:03 INFO - Running post-action listener: _resource_record_post_action 10:51:03 INFO - [mozharness: 2017-06-13 17:51:03.869057Z] Finished read-buildbot-config step (success) 10:51:03 INFO - [mozharness: 2017-06-13 17:51:03.869201Z] Running download-and-extract step. 10:51:03 INFO - Running pre-action listener: _resource_record_pre_action 10:51:03 INFO - Running main action method: download_and_extract 10:51:03 INFO - mkdir: /builds/slave/test/build/tests 10:51:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:03 INFO - trying https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 10:51:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/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 10:51:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/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 10:51:04 INFO - Downloaded 1328 bytes. 10:51:04 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 10:51:04 INFO - Using the following test package requirements: 10:51:04 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 10:51:04 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 10:51:04 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 10:51:04 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 10:51:04 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'jsshell-mac.zip'], 10:51:04 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 10:51:04 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 10:51:04 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 10:51:04 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 10:51:04 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 10:51:04 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 10:51:04 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 10:51:04 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:51:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 10:51:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:51:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 10:51:06 INFO - Content-Length response header: 59539647 10:51:06 INFO - Bytes received: 59539647 10:51:25 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 10:51:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 10:51:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:51:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 10:51:27 INFO - Content-Length response header: 59539647 10:51:27 INFO - Bytes received: 59539647 10:51:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 10:51:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:51:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 10:51:45 INFO - Content-Length response header: 14450022 10:51:45 INFO - Bytes received: 14450022 10:51:45 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')]} 10:51:45 INFO - trying https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 10:51:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 10:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/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 10:51:47 INFO - Downloaded 69334670 bytes. 10:51:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 10:51:47 INFO - mkdir: /builds/slave/test/properties 10:51:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:51:47 INFO - Writing to file /builds/slave/test/properties/build_url 10:51:47 INFO - Contents: 10:51:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 10:51:47 INFO - Running post-action listener: _resource_record_post_action 10:51:47 INFO - Running post-action listener: set_extra_try_arguments 10:51:47 INFO - [mozharness: 2017-06-13 17:51:47.841276Z] Finished download-and-extract step (success) 10:51:47 INFO - [mozharness: 2017-06-13 17:51:47.841443Z] Running populate-webroot step. 10:51:47 INFO - Running pre-action listener: _resource_record_pre_action 10:51:47 INFO - Running main action method: populate_webroot 10:51:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:51:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:51:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:51:47 INFO - '--tppagecycles', 10:51:47 INFO - '7'], 10:51:47 INFO - 'tests': ['tsvgm']}}, 10:51:47 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:51:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:51:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:51:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['damp', 'tps']}, 10:51:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'tests': ['damp', 'tps']}, 10:51:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['dromaeo_dom']}, 10:51:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:51:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:51:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:51:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:51:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['a11yr', 10:51:47 INFO - 'ts_paint', 10:51:47 INFO - 'tpaint', 10:51:47 INFO - 'sessionrestore', 10:51:47 INFO - 'sessionrestore_no_auto_restore', 10:51:47 INFO - 'tabpaint']}, 10:51:47 INFO - 'other-e10s': {'tests': ['a11yr', 10:51:47 INFO - 'ts_paint', 10:51:47 INFO - 'tpaint', 10:51:47 INFO - 'sessionrestore', 10:51:47 INFO - 'sessionrestore_no_auto_restore', 10:51:47 INFO - 'tabpaint']}, 10:51:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:51:47 INFO - 'ts_paint', 10:51:47 INFO - 'tpaint', 10:51:47 INFO - 'sessionrestore', 10:51:47 INFO - 'sessionrestore_no_auto_restore', 10:51:47 INFO - 'tabpaint']}, 10:51:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:51:47 INFO - 'ts_paint', 10:51:47 INFO - 'tpaint', 10:51:47 INFO - 'sessionrestore', 10:51:47 INFO - 'sessionrestore_no_auto_restore', 10:51:47 INFO - 'tabpaint']}, 10:51:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['a11yr', 10:51:47 INFO - 'ts_paint', 10:51:47 INFO - 'tpaint', 10:51:47 INFO - 'sessionrestore', 10:51:47 INFO - 'sessionrestore_no_auto_restore', 10:51:47 INFO - 'tabpaint']}, 10:51:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['a11yr', 10:51:47 INFO - 'ts_paint', 10:51:47 INFO - 'tpaint', 10:51:47 INFO - 'sessionrestore', 10:51:47 INFO - 'sessionrestore_no_auto_restore', 10:51:47 INFO - 'tabpaint']}, 10:51:47 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:51:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:51:47 INFO - 'bloom_basic_ref']}, 10:51:47 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:51:47 INFO - 'talos_options': ['--mitmproxy', 10:51:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:51:47 INFO - '--firstNonBlankPaint'], 10:51:47 INFO - 'tests': ['quantum_pageload_google', 10:51:47 INFO - 'quantum_pageload_youtube', 10:51:47 INFO - 'quantum_pageload_amazon', 10:51:47 INFO - 'quantum_pageload_facebook']}, 10:51:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['tsvgx', 10:51:47 INFO - 'tsvgr_opacity', 10:51:47 INFO - 'tart', 10:51:47 INFO - 'tscrollx', 10:51:47 INFO - 'cart', 10:51:47 INFO - 'tsvg_static']}, 10:51:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:51:47 INFO - 'tsvgr_opacity', 10:51:47 INFO - 'tart', 10:51:47 INFO - 'tscrollx', 10:51:47 INFO - 'cart', 10:51:47 INFO - 'tsvg_static']}, 10:51:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'talos_options': ['--disable-e10s'], 10:51:47 INFO - 'tests': ['tp5o']}, 10:51:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:51:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'talos_options': ['--disable-e10s', 10:51:47 INFO - '--xperf_path', 10:51:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:47 INFO - 'tests': ['tp5n']}, 10:51:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:51:47 INFO - 'talos_options': ['--xperf_path', 10:51:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:47 INFO - 'tests': ['tp5n']}}, 10:51:47 INFO - 'talos.zip': {'path': '', 10:51:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:51:47 INFO - Running post-action listener: _resource_record_post_action 10:51:47 INFO - [mozharness: 2017-06-13 17:51:47.863753Z] Finished populate-webroot step (success) 10:51:47 INFO - [mozharness: 2017-06-13 17:51:47.863879Z] Running create-virtualenv step. 10:51:47 INFO - Running pre-action listener: _resource_record_pre_action 10:51:47 INFO - Running main action method: create_virtualenv 10:51:47 INFO - Creating virtualenv /builds/slave/test/build/venv 10:51:47 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 10:51:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:51:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:51:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:51:47 INFO - Using real prefix '/tools/python27' 10:51:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:51:48 INFO - Installing distribute.............................................................................................................................................................................................done. 10:51:51 INFO - Installing pip.................done. 10:51:51 INFO - Return code: 0 10:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:51:51 INFO - Reading from file tmpfile_stdout 10:51:51 INFO - Output received: 10:51:51 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) 10:51:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:51 INFO - 'HOME': '/Users/cltbld', 10:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:51 INFO - 'LOGNAME': 'cltbld', 10:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:51 INFO - 'MOZ_NO_REMOTE': '1', 10:51:51 INFO - 'NO_EM_RESTART': '1', 10:51:51 INFO - 'PAGER': '/bin/cat', 10:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:51 INFO - 'PWD': '/builds/slave/test', 10:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:51 INFO - 'SHELL': '/bin/bash', 10:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:51:51 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:51 INFO - 'USER': 'cltbld', 10:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:51 INFO - 'XPC_FLAGS': '0x0', 10:51:51 INFO - 'XPC_SERVICE_NAME': '0', 10:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:52 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 10:51:52 INFO - Cleaning up... 10:51:52 INFO - Return code: 0 10:51:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:51:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:51:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:52 INFO - 'HOME': '/Users/cltbld', 10:51:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:52 INFO - 'LOGNAME': 'cltbld', 10:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:52 INFO - 'MOZ_NO_REMOTE': '1', 10:51:52 INFO - 'NO_EM_RESTART': '1', 10:51:52 INFO - 'PAGER': '/bin/cat', 10:51:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:52 INFO - 'PWD': '/builds/slave/test', 10:51:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:52 INFO - 'SHELL': '/bin/bash', 10:51:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:51:52 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:51:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:52 INFO - 'USER': 'cltbld', 10:51:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:52 INFO - 'XPC_FLAGS': '0x0', 10:51:52 INFO - 'XPC_SERVICE_NAME': '0', 10:51:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:52 INFO - Downloading/unpacking psutil>=3.1.1 10:51:52 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 10:51:52 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 10:51:52 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 10:51:52 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 10:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:51:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:54 INFO - Installing collected packages: psutil 10:51:54 INFO - Running setup.py install for psutil 10:51:54 INFO - building 'psutil._psutil_osx' extension 10:51:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:51:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:51:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:51:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:51:54 INFO - building 'psutil._psutil_posix' extension 10:51:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:51:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:51:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:51:55 INFO - ^ 10:51:55 INFO - 1 warning generated. 10:51:55 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 10:51:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:51:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:51:55 INFO - Successfully installed psutil 10:51:55 INFO - Cleaning up... 10:51:55 INFO - Return code: 0 10:51:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:51:55 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')]} 10:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:55 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')]} 10:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:55 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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:51:55 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 10:51:55 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 10:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:55 INFO - 'HOME': '/Users/cltbld', 10:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:55 INFO - 'LOGNAME': 'cltbld', 10:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:55 INFO - 'MOZ_NO_REMOTE': '1', 10:51:55 INFO - 'NO_EM_RESTART': '1', 10:51:55 INFO - 'PAGER': '/bin/cat', 10:51:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:55 INFO - 'PWD': '/builds/slave/test', 10:51:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:55 INFO - 'SHELL': '/bin/bash', 10:51:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:51:55 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:51:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:55 INFO - 'USER': 'cltbld', 10:51:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:55 INFO - 'XPC_FLAGS': '0x0', 10:51:55 INFO - 'XPC_SERVICE_NAME': '0', 10:51:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:51:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:51:57 INFO - Installing collected packages: mozsystemmonitor 10:51:57 INFO - Running setup.py install for mozsystemmonitor 10:51:57 INFO - Successfully installed mozsystemmonitor 10:51:57 INFO - Cleaning up... 10:51:57 INFO - Return code: 0 10:51:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:51:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51: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')]} 10:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:58 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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:51:58 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 10:51:58 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 10:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:58 INFO - 'HOME': '/Users/cltbld', 10:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:58 INFO - 'LOGNAME': 'cltbld', 10:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:58 INFO - 'MOZ_NO_REMOTE': '1', 10:51:58 INFO - 'NO_EM_RESTART': '1', 10:51:58 INFO - 'PAGER': '/bin/cat', 10:51:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:58 INFO - 'PWD': '/builds/slave/test', 10:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:58 INFO - 'SHELL': '/bin/bash', 10:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:51:58 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:58 INFO - 'USER': 'cltbld', 10:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:58 INFO - 'XPC_FLAGS': '0x0', 10:51:58 INFO - 'XPC_SERVICE_NAME': '0', 10:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:58 INFO - Downloading/unpacking jsonschema==2.5.1 10:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:51:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:52:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:52:00 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 10:52:00 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 10:52:00 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 10:52:00 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 10:52:01 INFO - Downloading functools32-3.2.3-2.tar.gz 10:52:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:52:01 INFO - warning: no files found matching '*.txt' 10:52:01 INFO - no previously-included directories found matching 'build' 10:52:01 INFO - no previously-included directories found matching 'dist' 10:52:01 INFO - no previously-included directories found matching '.git*' 10:52:01 INFO - Installing collected packages: jsonschema, functools32 10:52:01 INFO - Running setup.py install for functools32 10:52:01 INFO - warning: no files found matching '*.txt' 10:52:01 INFO - no previously-included directories found matching 'build' 10:52:01 INFO - no previously-included directories found matching 'dist' 10:52:01 INFO - no previously-included directories found matching '.git*' 10:52:01 INFO - Successfully installed jsonschema functools32 10:52:01 INFO - Cleaning up... 10:52:01 INFO - Return code: 0 10:52:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:52:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:52:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:52:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:52:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:01 INFO - 'HOME': '/Users/cltbld', 10:52:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:01 INFO - 'LOGNAME': 'cltbld', 10:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:01 INFO - 'MOZ_NO_REMOTE': '1', 10:52:01 INFO - 'NO_EM_RESTART': '1', 10:52:01 INFO - 'PAGER': '/bin/cat', 10:52:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:01 INFO - 'PWD': '/builds/slave/test', 10:52:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:01 INFO - 'SHELL': '/bin/bash', 10:52:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:52:01 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:52:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:01 INFO - 'USER': 'cltbld', 10:52:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:01 INFO - 'XPC_FLAGS': '0x0', 10:52:01 INFO - 'XPC_SERVICE_NAME': '0', 10:52:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:52:01 INFO - Cleaning up... 10:52:01 INFO - Return code: 0 10:52:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:52:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:52:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:52:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:52:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:01 INFO - 'HOME': '/Users/cltbld', 10:52:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:01 INFO - 'LOGNAME': 'cltbld', 10:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:01 INFO - 'MOZ_NO_REMOTE': '1', 10:52:01 INFO - 'NO_EM_RESTART': '1', 10:52:01 INFO - 'PAGER': '/bin/cat', 10:52:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:01 INFO - 'PWD': '/builds/slave/test', 10:52:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:01 INFO - 'SHELL': '/bin/bash', 10:52:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:52:01 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:52:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:01 INFO - 'USER': 'cltbld', 10:52:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:01 INFO - 'XPC_FLAGS': '0x0', 10:52:01 INFO - 'XPC_SERVICE_NAME': '0', 10:52:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:01 INFO - Downloading/unpacking blobuploader==1.2.4 10:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:03 INFO - Downloading blobuploader-1.2.4.tar.gz 10:52:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:52:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:52:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:04 INFO - Downloading docopt-0.6.1.tar.gz 10:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:52:04 INFO - Installing collected packages: blobuploader, requests, docopt 10:52:04 INFO - Running setup.py install for blobuploader 10:52:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:52:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:52:04 INFO - Running setup.py install for requests 10:52:04 INFO - Running setup.py install for docopt 10:52:05 INFO - Successfully installed blobuploader requests docopt 10:52:05 INFO - Cleaning up... 10:52:05 INFO - Return code: 0 10:52:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:05 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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:52:05 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 10:52:05 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 10:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:05 INFO - 'HOME': '/Users/cltbld', 10:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:05 INFO - 'LOGNAME': 'cltbld', 10:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:05 INFO - 'MOZ_NO_REMOTE': '1', 10:52:05 INFO - 'NO_EM_RESTART': '1', 10:52:05 INFO - 'PAGER': '/bin/cat', 10:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:05 INFO - 'PWD': '/builds/slave/test', 10:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:05 INFO - 'SHELL': '/bin/bash', 10:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:52:05 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:05 INFO - 'USER': 'cltbld', 10:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:05 INFO - 'XPC_FLAGS': '0x0', 10:52:05 INFO - 'XPC_SERVICE_NAME': '0', 10:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:05 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-fbJlqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:05 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ecreAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:05 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-3sYx00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:05 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-RAlBB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:05 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-e8DghV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:05 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Vn6AtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-sDE9OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-5oOV7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ATzhkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ng4oA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-MgIvAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-gkldyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-T40OHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-aiujoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:06 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-iZQJDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:07 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-D1YXQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:07 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-CGMoK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:52:07 INFO - Running setup.py install for manifestparser 10:52:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Running setup.py install for mozcrash 10:52:07 INFO - Running setup.py install for mozdebug 10:52:07 INFO - Running setup.py install for mozdevice 10:52:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:52:07 INFO - Running setup.py install for mozfile 10:52:07 INFO - Running setup.py install for mozhttpd 10:52:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozinfo 10:52:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozInstall 10:52:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozleak 10:52:08 INFO - Running setup.py install for mozlog 10:52:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for moznetwork 10:52:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:52:08 INFO - Running setup.py install for mozprocess 10:52:09 INFO - Running setup.py install for mozprofile 10:52:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:52:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:52:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:52:09 INFO - Running setup.py install for mozrunner 10:52:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:52:09 INFO - Running setup.py install for mozscreenshot 10:52:09 INFO - Running setup.py install for moztest 10:52:09 INFO - Running setup.py install for mozversion 10:52:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:52:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:52:09 INFO - Cleaning up... 10:52:09 INFO - Return code: 0 10:52:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:09 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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:52:09 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 10:52:09 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 10:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:09 INFO - 'HOME': '/Users/cltbld', 10:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:09 INFO - 'LOGNAME': 'cltbld', 10:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:09 INFO - 'MOZ_NO_REMOTE': '1', 10:52:09 INFO - 'NO_EM_RESTART': '1', 10:52:09 INFO - 'PAGER': '/bin/cat', 10:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:09 INFO - 'PWD': '/builds/slave/test', 10:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:09 INFO - 'SHELL': '/bin/bash', 10:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:52:09 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:09 INFO - 'USER': 'cltbld', 10:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:09 INFO - 'XPC_FLAGS': '0x0', 10:52:09 INFO - 'XPC_SERVICE_NAME': '0', 10:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ifug7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-BNaG6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-YhL7ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-l1Y9T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-abSxgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-oOJVcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Gw8cDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-GTxky2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:10 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)) 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-DTrN_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-JeSFCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ZgYKgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-h0aosh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-TAiieo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-sngLXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-bBVIjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-TmQaAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:11 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)) 10:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:11 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-LxbAfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:11 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)) 10:52:11 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)) 10:52:11 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)) 10:52:11 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)) 10:52:11 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)) 10:52:11 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)) 10:52:11 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)) 10:52:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:52:11 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 10:52:11 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 10:52:11 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 10:52:11 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 10:52:13 INFO - Downloading blessings-1.6.tar.gz 10:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:52:13 INFO - Installing collected packages: blessings 10:52:13 INFO - Running setup.py install for blessings 10:52:13 INFO - Successfully installed blessings 10:52:13 INFO - Cleaning up... 10:52:13 INFO - Return code: 0 10:52:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:52:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:13 INFO - Reading from file tmpfile_stdout 10:52:13 INFO - Current package versions: 10:52:13 INFO - blessings == 1.6 10:52:13 INFO - blobuploader == 1.2.4 10:52:13 INFO - docopt == 0.6.1 10:52:13 INFO - functools32 == 3.2.3-2 10:52:13 INFO - jsonschema == 2.5.1 10:52:13 INFO - manifestparser == 1.1 10:52:13 INFO - mozInstall == 1.13 10:52:13 INFO - mozcrash == 1.0 10:52:13 INFO - mozdebug == 0.1 10:52:13 INFO - mozdevice == 0.51 10:52:13 INFO - mozfile == 1.2 10:52:13 INFO - mozhttpd == 0.7 10:52:13 INFO - mozinfo == 0.10 10:52:13 INFO - mozleak == 0.1 10:52:13 INFO - mozlog == 3.4 10:52:13 INFO - moznetwork == 0.27 10:52:13 INFO - mozprocess == 0.25 10:52:13 INFO - mozprofile == 0.28 10:52:13 INFO - mozrunner == 6.13 10:52:13 INFO - mozscreenshot == 0.1 10:52:13 INFO - mozsystemmonitor == 0.3 10:52:13 INFO - moztest == 0.8 10:52:13 INFO - mozversion == 1.4 10:52:13 INFO - psutil == 3.1.1 10:52:13 INFO - requests == 1.2.3 10:52:13 INFO - wsgiref == 0.1.2 10:52:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52: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')]} 10:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52: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')]} 10:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:13 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 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:52:13 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 10:52:13 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 10:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:13 INFO - 'HOME': '/Users/cltbld', 10:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:13 INFO - 'LOGNAME': 'cltbld', 10:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:13 INFO - 'MOZ_NO_REMOTE': '1', 10:52:13 INFO - 'NO_EM_RESTART': '1', 10:52:13 INFO - 'PAGER': '/bin/cat', 10:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:13 INFO - 'PWD': '/builds/slave/test', 10:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:13 INFO - 'SHELL': '/bin/bash', 10:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:52:13 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:13 INFO - 'USER': 'cltbld', 10:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:13 INFO - 'XPC_FLAGS': '0x0', 10:52:13 INFO - 'XPC_SERVICE_NAME': '0', 10:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:14 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)) 10:52:14 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)) 10:52:14 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)) 10:52:14 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)) 10:52:14 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)) 10:52:14 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)) 10:52:14 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)) 10:52:14 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)) 10:52:14 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)) 10:52:14 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:52:14 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 10:52:14 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 10:52:14 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 10:52:14 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 10:52:15 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:52:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:52:15 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)) 10:52:15 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)) 10:52:15 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)) 10:52:15 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)) 10:52:15 INFO - Installing collected packages: marionette-driver 10:52:15 INFO - Running setup.py install for marionette-driver 10:52:15 INFO - Successfully installed marionette-driver 10:52:15 INFO - Cleaning up... 10:52:16 INFO - Return code: 0 10:52:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:52: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')]} 10:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52: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')]} 10:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c4c7c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b1d7ab30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, '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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 10:52: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', 'jsonschema'] in /builds/slave/test/build 10:52: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 jsonschema 10:52:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:52:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:16 INFO - 'HOME': '/Users/cltbld', 10:52:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:16 INFO - 'LOGNAME': 'cltbld', 10:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:16 INFO - 'MOZ_NO_REMOTE': '1', 10:52:16 INFO - 'NO_EM_RESTART': '1', 10:52:16 INFO - 'PAGER': '/bin/cat', 10:52:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:16 INFO - 'PWD': '/builds/slave/test', 10:52:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:16 INFO - 'SHELL': '/bin/bash', 10:52:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:52:16 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:52:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:16 INFO - 'USER': 'cltbld', 10:52:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:16 INFO - 'XPC_FLAGS': '0x0', 10:52:16 INFO - 'XPC_SERVICE_NAME': '0', 10:52:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:52:16 INFO - Cleaning up... 10:52:16 INFO - Return code: 0 10:52:16 INFO - Running post-action listener: _resource_record_post_action 10:52:16 INFO - Running post-action listener: _start_resource_monitoring 10:52:16 INFO - Starting resource monitoring. 10:52:16 INFO - [mozharness: 2017-06-13 17:52:16.311623Z] Finished create-virtualenv step (success) 10:52:16 INFO - [mozharness: 2017-06-13 17:52:16.312688Z] Running install step. 10:52:16 INFO - Running pre-action listener: _resource_record_pre_action 10:52:16 INFO - Running main action method: install 10:52:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:16 INFO - Reading from file tmpfile_stdout 10:52:16 INFO - Detecting whether we're running mozinstall >=1.0... 10:52:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:52:16 INFO - Reading from file tmpfile_stdout 10:52:16 INFO - Output received: 10:52:16 INFO - Usage: mozinstall [options] installer 10:52:16 INFO - Options: 10:52:16 INFO - -h, --help show this help message and exit 10:52:16 INFO - -d DEST, --destination=DEST 10:52:16 INFO - Directory to install application into. [default: 10:52:16 INFO - "/builds/slave/test"] 10:52:16 INFO - --app=APP Application being installed. [default: firefox] 10:52:16 INFO - mkdir: /builds/slave/test/build/application 10:52:16 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'] 10:52:16 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 10:52:38 INFO - Reading from file tmpfile_stdout 10:52:38 INFO - Output received: 10:52:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:52:38 INFO - Running post-action listener: _resource_record_post_action 10:52:38 INFO - [mozharness: 2017-06-13 17:52:38.652760Z] Finished install step (success) 10:52:38 INFO - [mozharness: 2017-06-13 17:52:38.652901Z] Running setup-mitmproxy step. 10:52:38 INFO - Running pre-action listener: _resource_record_pre_action 10:52:38 INFO - Running main action method: setup_mitmproxy 10:52:38 INFO - Skipping: mitmproxy is not required 10:52:38 INFO - Running post-action listener: _resource_record_post_action 10:52:38 INFO - [mozharness: 2017-06-13 17:52:38.653548Z] Finished setup-mitmproxy step (success) 10:52:38 INFO - [mozharness: 2017-06-13 17:52:38.653673Z] Running run-tests step. 10:52:38 INFO - Running pre-action listener: _resource_record_pre_action 10:52:38 INFO - Running main action method: run_tests 10:52:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:52:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:52:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:52:38 INFO - 2017-06-13 10:52:38.669 screenresolution[8423:161818] starting screenresolution argv=screenresolution get 10:52:38 INFO - 2017-06-13 10:52:38.671 screenresolution[8423:161818] Display 0: 1600x1200x32@60 10:52:38 INFO - 2017-06-13 10:52:38.677 screenresolution[8424:161821] starting screenresolution argv=screenresolution list 10:52:38 INFO - Available Modes on Display 0 10:52:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:52:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:52:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:52:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:52:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:52:38 INFO - Intel Iris: 10:52:38 INFO - Chipset Model: Intel Iris 10:52:38 INFO - Type: GPU 10:52:38 INFO - Bus: Built-In 10:52:38 INFO - VRAM (Dynamic, Max): 1536 MB 10:52:38 INFO - Vendor: Intel (0x8086) 10:52:38 INFO - Device ID: 0x0a2e 10:52:38 INFO - Revision ID: 0x0009 10:52:38 INFO - Displays: 10:52:38 INFO - Display: 10:52:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:52:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:52:38 INFO - Display Serial Number: 1600x1200 60 10:52:38 INFO - Main Display: Yes 10:52:38 INFO - Mirror: Off 10:52:38 INFO - Online: Yes 10:52:38 INFO - Rotation: Supported 10:52:38 INFO - Return code: 0 10:52:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:52:38 INFO - Python 2.7.3 10:52:38 INFO - Return code: 0 10:52:38 INFO - grabbing minidump binary from tooltool 10:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:38 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 0x10c21dd30>, '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 0x10c1e7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:52:38 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 10:52:38 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 10:52:38 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 10:52:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:52:39 INFO - Return code: 0 10:52:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:52:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:52:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:52:39 INFO - ENV: RUST_BACKTRACE is now 1 10:52:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:52:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:52:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 10:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0161 --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 10:52:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKhfXtoZ1N/Render', 10:52:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:39 INFO - 'HOME': '/Users/cltbld', 10:52:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:39 INFO - 'LOGNAME': 'cltbld', 10:52:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:52:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:39 INFO - 'MOZ_NO_REMOTE': '1', 10:52:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:52:39 INFO - 'NO_EM_RESTART': '1', 10:52:39 INFO - 'PAGER': '/bin/cat', 10:52:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:39 INFO - 'PWD': '/builds/slave/test', 10:52:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:52:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:39 INFO - 'RUST_BACKTRACE': '1', 10:52:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:52:39 INFO - 'SHELL': '/bin/bash', 10:52:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ku2kcix7w2/Listeners', 10:52:39 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 10:52:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:39 INFO - 'USER': 'cltbld', 10:52:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:39 INFO - 'XPC_FLAGS': '0x0', 10:52:39 INFO - 'XPC_SERVICE_NAME': '0', 10:52:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 10:52:39 INFO - mozversion application_buildid: 20170613071001 10:52:39 INFO - mozversion application_changeset: 6558a0d4e3e76b6115c1717f6d4dfb0fa58c4873 10:52:39 INFO - mozversion application_display_name: Nightly 10:52:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:52:39 INFO - mozversion application_name: Firefox 10:52:39 INFO - mozversion application_remotingname: firefox 10:52:39 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 10:52:39 INFO - mozversion application_vendor: Mozilla 10:52:39 INFO - mozversion application_version: 56.0a1 10:52:39 INFO - mozversion platform_buildid: 20170613071001 10:52:39 INFO - mozversion platform_changeset: 6558a0d4e3e76b6115c1717f6d4dfb0fa58c4873 10:52:39 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 10:52:39 INFO - mozversion platform_version: 56.0a1 10:52:39 INFO - using testdate: 1497376359 10:52:39 INFO - actual date: 1497376359 10:52:39 INFO - starting webserver on 'localhost:52490' 10:52:39 INFO - SUITE-START | Running 2 tests 10:52:39 INFO - TEST-START | dromaeo_css 10:52:39 INFO - operating with platform_type : mac_ 10:52:39 INFO - Initialising browser for dromaeo_css test... 10:52:39 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:52490/getInfo.html -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpMpaqjL/profile 10:52:39 INFO - TEST-INFO | started process 8434 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:52490/getInfo.html -foreground) 10:53:13 INFO - TEST-INFO | 8434: killed by SIGTERM 10:53:13 INFO - Browser initialized. 10:53:13 INFO - Running cycle 1/1 for dromaeo_css test... 10:53:13 INFO - TEST-INFO | started process 8440 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpMpaqjL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:53:15 INFO - PID 8440 | 2017-06-13 10:53:15.009 plugin-container[8441:162298] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 10:53:15 INFO - PID 8440 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:15 INFO - PID 8440 | 2017-06-13 10:53:15.010 plugin-container[8441:162298] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 10:53:15 INFO - PID 8440 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:15 INFO - PID 8440 | 2017-06-13 10:53:15.010 plugin-container[8441:162298] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:53:15 INFO - PID 8440 | 2017-06-13 10:53:15.845 plugin-container[8442:162344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 10:53:15 INFO - PID 8440 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:15 INFO - PID 8440 | 2017-06-13 10:53:15.845 plugin-container[8442:162344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 10:53:15 INFO - PID 8440 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:15 INFO - PID 8440 | 2017-06-13 10:53:15.846 plugin-container[8442:162344] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:53:25 INFO - PID 8440 | RSS: Main: 251777024 10:53:25 INFO - PID 8440 | 10:56:28 INFO - PID 8440 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52490/tests/dromaeo/cssquery-ext.html) 10:56:30 INFO - PID 8440 | RSS: Main: 268734464 10:56:30 INFO - PID 8440 | 10:59:32 INFO - PID 8440 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-ext.html (next: http://localhost:52490/tests/dromaeo/cssquery-jquery.html) 10:59:33 INFO - PID 8440 | RSS: Main: 279908352 10:59:33 INFO - PID 8440 | 11:02:34 INFO - PID 8440 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52490/tests/dromaeo/cssquery-mootools.html) 11:02:35 INFO - PID 8440 | RSS: Main: 282058752 11:02:35 INFO - PID 8440 | 11:05:37 INFO - PID 8440 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52490/tests/dromaeo/cssquery-prototype.html) 11:05:37 INFO - PID 8440 | RSS: Main: 285229056 11:05:37 INFO - PID 8440 | 11:08:40 INFO - PID 8440 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52490/tests/dromaeo/cssquery-yui.html) 11:08:41 INFO - PID 8440 | RSS: Main: 286441472 11:08:41 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-yui.html (next: http://localhost:52490/tests/dromaeo/cssquery-dojo.html) 11:11:43 INFO - PID 8440 | RSS: Main: 285601792 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | __start_tp_report 11:11:43 INFO - PID 8440 | _x_x_mozilla_page_load 11:11:43 INFO - PID 8440 | _x_x_mozilla_page_load_details 11:11:43 INFO - PID 8440 | |i|pagename|runs| 11:11:43 INFO - PID 8440 | |0;dojo.html;3389.271306669066;3462.913427164322;3480.8607655693772;3483.912902177444;3566.1084728817814;6126.853582554517;6133.61010758268;6151.923413402647;6224.28420731616;6268.905966410507;6166.722497487613;6212.658303793301;6220.222472190976;6222.97320942044;6233.563650544464;4886.1204983103025;5445.292112025431;5573.693446860421;5606.859828504279;5620.803271885485;5524.944750552483;5532.889342213153;5538.390777014525;5566.137248726433;5567.081431563787;5103.081445339819;5381.650192737479;5400.297961265011;5457.208704737831;5485.286912701363;5547.55619550435;5550.47270509301;5559.555235581184;5571.749271282802;5578.609497335188;5378.91931621026;5487.862803429906;5510.256115424407;5515.338159420855;5526.696031718254;6088.908666370008;6218.81343559694;6261.311255761862;6274.341194174592;6281.87436251277;6439.452646525051;6542.476601871839;6549.312322206148;6658.3674550917585;6745;6716.597004179765;6723.058771771955;6730.461563074942;6774.661266936633;6790.456763458911;5404.891902161935;5764.971175144097;6295.685215739286;6329.905051424233;6332.461740751995;6671.699773510204;6770.627615481196;6887.758928437581;6920.930790692129;6926.653667316614;5404.729763511808;6422.165118534654;6479.3520647935775;6490.0589414534625;6593.703283352261;6704.061431399608;6789.864202715918;6796.388325050769;6803;6811.523193376515;6550.312217217219;6686.665666716644;6692.163479565054;6692.83267918306;6707.195136583641;5697.772089116389;6632.137822083195;6664.600123992576;6690.163729533809;6714.496412768965;5238.92141617876;5240.921386179134;5277.155655095166;5300.708461034603;5322.813701520506;2687.784977201819;4364.861218003219;4719.433667959866;4737.976310118427;4752.358431611758;6239.313675495692;6275.309715931244;6351.110844481776;6367.968160159169;6379.170707807955;6820.829479263058;6827.044213810071;6844.384005439534;6868.553544019623;6870.484713646497;5322.361316642027;6157.753689852445;6301.6534090625455;6315.400036996478;6349.904751428732;5774.509166720792;5835.270591176103;5848.152017957457;5889.970550147222;6018.518518518508;6283.685815709287;6344.429001389897;6701.396874281338;6743.157105361928;6795.45636349081;6568.967155164193;6732.899006515004;6781.491388145811;6801.251862295243;6810.114685090906;6953;6956.513044087068;6962.060121883481;6967.651617419007;6973.965130174317;6612.8346791330605;6662.966685166543;6688.899666505104;6693.832654183684;6696.430803381767;6551.80344589663;6656.633885136364;6776.7628133015105;6818;6826.726930922906;6346.397092276226;6424.9036264455135;6536.215654121535;6542.313057128933;6551.868962620816;6500.577462464925;6568.671566421564;6579.703913324008;6623.4038936495945;6633.867322653617;5392.784288628411;6264.937350626436;6436.935630643634;6437.452816510649;6463.192100987377;6330.461910737455;6347.428731414195;6380.298167201697;6380.3619638035825;6408.102865598913;5377.327834020747;6339.77810776639;6343.429091381613;6345.17512723343;6357.300696923242 11:11:43 INFO - PID 8440 | |1;ext.html;795.5107608820573;805.5005896344268;807.8343939492405;831.7005877883961;841.5161282262701;37027.25945481089;38075;38095.809520952425;38103.85688429345;38265.617343826554;37813.62186378135;38052.42921356175;38054.8097259514;38085.23829523415;38130.8093459533;7271.12746470423;7374.852502949938;7403;7403.037605111328;7413.777586672409;7262.818429539251;7315.56106633601;7344.2288559701265;7364.889526657104;7371.483996120273;7239.782806515813;7274.054372931511;7298.343149116578;8008.63961121748;8213.137620549845;8209.261166495015;8260.380471464665;8274.544900030556;8286;8293.626786794579;7985.600719964036;8011;8067;8092;8121.593920303962;27060.59409108865;35199;35255.64744352549;35503.82248088769;35531.75638852628;3561.1453251219523;3601.099725068733;3601.1897323102353;3602.7478076534653;3606.1165014571598;29594.964176253943;29666;29667.40665186706;29680.554791678143;29736.40527189444;3011.804232724866;3024.773142014336;3069;3095.7059079387423;3119.9688003119904;240841;242124.15751685045;244222;299388;300456;46621;46953;47323.52676473191;47355.5264447358;47523.524764751906;48675;48691.78270543194;48710;48787;48849.51150488521;14064.015518913578;14195.077319974169;14197.006209565438;14219.431222751182;14255.287235638176;11652.184347095792;11675.708107297385;11681.182317237712;11706.590269340531;11709.297442153498;11301.26541774782;13263;13435.865641343657;13524.729505409836;13641.976851736159;5467.644603100785;5539.584531160179;6031.366706495843;6113.786017489367;6132.877342453126;916.7433118726767;976.6288810252058;988.0377214801905;1059.2902755154066;1064.387976913278;20254.69617955732;21434.678479822815;22500.774992250197;22772.089116435156;23528.82355882199;1088.733260351219;1106.6458733205297;1117.5585643670868;1160.8142697168412;1162.5698491558007;11510.884891151149;11579.189456737948;11643.301401915744;11657.417129143509;12036.277823330629;320990;321202;322646;323954;324409;52900.47099528956;53192;53277.668058298856;53303;53356.73321633367;1501.2868887278455;1512.455516014234;1531.0660497096762;1537.3235776258412;1540.8536189062245;19789.50526236798;21444.88820686097;22641.320760377213;22734.431639209153;23026.194083207007;36828.26343473169;36871.63128368682;36934.445983309735;36943.261134776614;36968;273270;273733;274307.88538171543;274497;274676;28047.57928631113;28492.57261141126;28522.57216141718;32968.67031329656;33733.662663373056;40178.59821401749;40659.59340406558;41033.179336412504;41268.587314126475;41446.378304324855;25194.874025629753;27111.051113210942;27170.456590868467;27518.899244030014;27971.44057118887;28638.42068921023;29458.116256511483;29993.55009674813;30109;30162.793488260224;32453.51319730253;35549.64450355567;35596.82201588975;35618.821905890305;35715.82142089273;24823;27299;28667.28331791722;28711;29893.95371161999;21970.670439943733;22948.655770163794;23524.76475235294;23714.644280335462;23724.644130337714 11:11:43 INFO - PID 8440 | |2;jquery.html;415.49102349621734;487.6050399176665;489.8383533433967;496.9043259612555;501.60373305088984;22090;22922.083116675356;22938.967746451428;22992.65511017336;23057;16374.345026198967;17382.739258911126;17403.390881319156;17430.825691743077;17749.64500709985;6047.87904241916;6243.406876346739;6247.9687601562155;6250.187475628162;6251.1560939273295;6051.727672254737;6101.389861013908;6164.660943648098;6179.721912513947;6191.411815877507;6136.171616831715;6141.815745527641;6144.84637884052;6146.416090471421;6152.323244443108;5522.6134170608075;5952.107183922403;6137.785177518787;6144.877102457948;6145.692715364236;4790.353302304214;5636.126400407943;5673.063944449161;5685.289338832646;5702.971485142548;28804.423911521862;29237.684304206363;29271.85364073187;29322.266943326373;29355.706442935512;1953.433504283756;2196.7803219678062;2224.177054489849;2238.597052530544;2245.0682966569016;17195;17490.513306368975;17596.824031759774;18342.26630934761;18431.907840460713;1992.4122383896786;2172.8044475997235;2179.9891000544894;2195.3194509702043;2231.0852550454397;107897;158247;162707;165507;169032;10884.564617415374;13685.452581896812;14557.344919478648;14734.484293049692;14753.557393278219;19688.507787305432;19836.305729299405;19871.701924471145;19872;19906.701399479018;8640.740777776677;9345.953270233742;9615.615375384907;9714.659986900566;9763.316567840184;8871.955640221757;8888.777780555538;8909.643614255358;8938.37431379797;8974.910250897537;7402.518836275625;7490.625468726542;8988.325875559229;9049.185573298435;9083.772905677279;2602.7267136950727;2604.5832666773226;2619.0702300682983;2621.5281249375294;2742.8491432970977;857.2713193785232;914.5884352041608;923.533615524156;960.3565611040619;988.3526290279879;20030.696469906146;20047.498812529804;20054.59890802176;20451.897740511202;20861.165553378;947.166493485742;981.7398389426808;991.7520619845038;1005.4872015272117;1017.7760892603616;8328.708495202638;10015;10268.435236062089;10636.787264254672;10914;548518;549993;553085;559016.4098358965;559181;145169;150187;151155.24422378038;165495.1725241366;167836;1185.2829038431796;1208.474313673555;1227.2881728597392;1233.802591585342;1236.7650146472142;21918.342449726533;22028;23242.1865234716;23286.534269314856;23768.286951391485;72011.63994179995;72705;74535.62732186304;75149.62425187838;75417.62291188509;94771.15686529415;95811;98505.52241716237;99162;100489.47307898136;10714.464276786286;11342.432878356214;11402.20184587104;11434.71413214643;11455.656330310105;14382.568522944328;17455.03997280111;17539;17651.55871103233;17712.291508339524;12861.421236044587;13958.441662333393;14015.509457169357;14120.435182592992;14146.43414263459;11316.20786544934;12713.618591442271;12779.936100319439;13206.80189797173;13349.866501334862;14189;14218.862491000902;14490.985631005728;14791.704165916406;14795.704085918436;11047.895210478888;11093.611723589636;11245.269057511236;11294.548218071184;11317.038051765692;12519.499220031461;12868;14855.702885942004;14882.925585372004;15145.469908553148 11:11:43 INFO - PID 8440 | |3;mootools.html;1426.9001169918106;1481.7406953783086;1501.0693370110532;1505.8104097762769;1517.9468718594844;12986.54547090852;13475.124116932404;13484.393202305895;13501.392437340319;13518.729625407486;11161.72095697606;11409.08727301816;11467.827982580247;11489.712757181074;12617.30604816737;2278.7949084582383;2638.195350418117;2689.7713694336003;2709.878055487498;2712.3354778079397;2650.09896635144;2683.9597406038924;2690.1794952539467;2697.136916186821;2711.267957651433;2652.774514166298;2661.095227622608;2663.227663977444;2664.866756662169;2676.986615066922;2221.7889300516426;2322.814174866007;2551.591745320749;2606.700229473606;2617.973820261792;2323.430759463942;2325.0351104291867;2671.839689618629;2769.2799872033415;2775.680796708384;11884.821727674092;12197.207181533173;13073;13240.20558766477;13241.470341186341;5957.672328021957;7468.991686122358;7471.140818805852;7476;7482.4762266641355;4354.6516278697945;4518.864434066983;4618.953810461886;4687.1328804171335;4691.249400096002;1358.5788405594296;1362.434589645296;1362.693393986355;1381.6822130909948;1382.5092092307286;19505.512362190915;19694.212231510723;19706.408807735646;19715.901420492948;19754.50613734668;6922.44245044871;6959.0257363969085;6992.61540615271;6997.615131167733;7070.328318809705;16127.75808362852;16247.512574622544;16344.427945021867;16355.754663680054;16358.754618680729;7648.349890259279;7655.770326890202;7664.6934122635585;7678.961605191938;7706.267904549059;6389.584676995981;6769.1876974763345;6771.28901465339;6827.112475378169;6844.623545704934;5146.665466744649;5299.655522391032;5325.760340784674;5333.253344531769;5451.754671039813;1615.4668959243422;1641.9917900410421;1649.043554738249;1664.4091347571439;1669.699454098274;810.528890064531;826.2563692676639;848.5078654380444;849.7195925344621;865.1787986288897;6754.8854439018;7139.393151582174;7745.341645960044;8065.435419520694;8088.595570221465;658.3778329478597;708.8688592610383;821.9342452603777;1006.9949650251701;1033.5762337441613;4758.428988521399;4795.136875362468;4842.080004799077;4890.706557606484;5687.431256874363;17412.564685882702;17978.370757023702;17992.460226193234;18126.456206313833;18180.272789088554;15985;17042.914785425994;17053.06208158563;17059.829401706073;17400.39098631542;8688.565571721389;9164.083591640783;9352.812943741086;9356.90643093574;9458;7288.599127048064;7985.441019128605;8055.275025247756;8112.675492980215;8249.463784853966;12825;14040.929795350958;15768.842311576738;16112.919435402748;16335;15386.769198462256;15520.068795872285;16096.758548621547;16282.755758663867;16821.41125060617;10661.41362225054;10923.672289831318;10931.289466184673;10956.726081848017;10963.616273430393;10406.687799366031;12217.816732748837;12388.318642474751;12398.5660501882;12420.813687794509;9654.758631034;9839.311248212556;10028.799424011624;10124.898751012395;10403.323783954018;9438.292128090283;9613.951930240584;9951.203903687543;10084.243681723754;10273.640422585173;8929.866052009354;8992.190702836777;8993.190612844613;9095.04502027304;9111;6194.318624951162;6418.032222866585;6433.163688720437;6502.284748677547;6922.757703480354;6459.483241340776;6529.539344147609;6676.499262555425;6749.3925546699065;7197.568145911262 11:11:43 INFO - PID 8440 | |4;prototype.html;369.68022660398765;409.83606557377067;419.71669123341735;451.503346319049;463.7055469776692;5794.275715535558;5918.727473593171;6354.3010268870385;6375.61746295222;6436.645984470852;5609.931486327227;5635.520980716634;5810.937117323353;6066.726997285122;6351.7459301628;4419.491758447772;4769.642276829234;4907.239377896422;4938.950610493885;4953.182724850395;4912;4942.308076869242;4968.701877887338;4988.301637770697;4994.775235114428;3773.9811300943456;4886.682365646239;4889.657723959324;4898.093852637256;4901.68139070961;4062.79686015701;4243.29985552385;4433.467983841927;4688.320193571913;4850.587700045483;3417.6411476795074;3466.3587236361354;4040.7575545467366;4065.6747460203114;4075.714699971003;4792.849716068118;5048.974755126201;5998.7600495980105;6104;6128.938710612882;4371.846985355497;5260.579153667697;5517.36550296717;5518.586106042023;5524.640898341595;5820.621659592113;6661.800145995579;6670.666466676646;6741.325867413219;6763.661816909184;4437.356583295436;4667.766611669403;4687.273472611751;4691.085238378518;4692.8122875084955;6222.595531290452;6941.236463988957;7005.579665220103;7076.363127318566;7079.433645308363;4183.728057676241;4754;4757.500462451462;4763.642726795504;4773.904521909542;4440.511543730187;4829.420469543677;4837.298591704183;4846.3699719037;4864.36763220779;4922.75386230687;5132.538071573576;5558.471945165203;5575.832725018256;5576;6299.149614802035;6698.698558564876;6729.798106056826;6736.191657001191;6750.426213771884;1412.6468382904275;1548.5276990517775;1668.5244705258942;1674.4055860169708;1674.9916250418669;945.5650400815564;1042.1241764823287;1114.9944250278697;1116.5310569560804;1121.5009320852305;5287.3126493556365;5327.7336133193185;5689.862027594498;6061.787642471523;6397.552171347961;5097.776533631902;5893.086571581412;6156.5998210116195;6392.4246817786625;6400;5168.862850172956;5603.8599035023635;6085.5435842312;6331.493480521547;6356.5697718014;3333.4999750037205;3344.2809795894;3616.132128289244;3620.3845346291196;3654.415293553072;5814;6716.775947545108;6809.216940051859;6865.5537390069485;7166.283371662897;6381.904271435932;7044.436445084381;7110.555902560899;7153.570785752871;7234.240404757425;5207.062728708869;5860.912086318624;6570.441512471493;6751.459883209233;7215;6904;6909.792706218822;6932.97887225398;6938.202106757787;6964.860702785814;6232.688365581612;6793.015012823014;6804.9319506804295;6823.829404264933;6840.452763778786;5588.217649529151;7064.32888875556;7113.786586402416;7122.786316410516;7133.2153463117975;4027.7180597359074;4313.590208930146;4491.079328737535;4731;4757.001029783784;6007.699615019144;6068.423499767515;6595.703193356407;6632.204135503577;7033.577985320897;6653.500987425866;6672.931391268722;6674.733010679518;6696.192028152469;6701.3298670134545;5759.65442073477;6049.911016017159;6541.672916354259;6646.667666616747;6651.933480665131;6516.283208847118;6559.868802624016;6563.750943283106;6567.140316165907;6595;6086.543509236736;6475.834349817078;6644.933550664625;6670.097992674146;6689.866202675822;5976.820695379146;6478.060681201106;6522.706478208397;6524.641144737085;6532.281449040697 11:11:43 INFO - PID 8440 | |5;yui.html;757.4962649837857;835.527926721402;837.932965362771;870.7982983482793;875.5578432891389;13554.728905421885;16946.83053169468;17078.829211707878;17099.82900170998;17767.55581110473;12730.363481825918;13176.60470185896;13197.538086166986;13229.272390018545;13522.323883805819;960.8078384323128;972.65470757881;987.7876256604828;989.2975987049203;990.2919412619975;817.6811043692965;980.289290264556;989.3470309595668;991.7966816802539;998.8351921932872;1043.8956104389576;1045.8901815309398;1048.3866937841372;1048.391932679048;1050.726811029134;949.9002604726508;1041.671873359893;1057.9947100264487;1059.8039362717846;1065.5844220753913;651.5894986158689;774.1948373691354;774.3031271855319;774.740461945249;775.9718373155591;16927.49217523476;17490.562735931577;17704.91147544254;17706.29174833005;17722.025288609122;17285.481435556954;17828.197731102133;17855.910720446445;17967.281308747635;17980.730289045674;12480.438380272819;14206.289685515683;14792.63018424548;14911.179885106314;14919.179445130514;3604.2611264690677;3620.167361506868;3630.4191329387436;3634.854605815764;3641.107928557494;114943;149092.2545387277;149564;159107.2044639781;160969;13670.794938075936;13876.58370248894;13983.860161398256;14051.156930583993;14225;23788.167414140422;25963.091291804696;26476;27015.189544313307;27258.5911211332;7967.00412448444;8128.105908350077;8154.469959452736;8168.673253069929;8179.468334558234;3974.8608798691907;4030.5969403060044;4031.959680403217;4033.032072302626;4051.9797401012806;4494.910101797946;4644.976775116171;4724.71651700899;4726;4733.14803335396;880.2341962492673;960.4429430930044;966.1594412860856;984.3306551544875;985.8718366612294;638.6327861479667;640.1677818835495;642.2036674523557;670.6345041952147;675.8141511084491;10414.791704165875;11762.117841161777;12070.973967212885;12115.878841211475;12194.451249693608;608.6986941464004;615.1203778596561;621.15522888872;622.2222222222222;660.085781193051;5492.69790161537;5518.19986102013;5570.442955704397;5863.472287494146;5877.970610147007;136899;141643.91780410937;142694.5730542702;142847.28576357051;144612;87109.51561937579;115011.42494287476;117469.4126529379;119548.8045119555;122234.77765222234;77711.2228877704;84651;96356;102034;103636;9576.425414475154;10987.506930881149;11706.648800535997;11881.22772019816;12325.383730813244;42948.926276843325;44316.11367772691;45910;46173.76913115413;47262.87317070671;59089.70455147697;63611.363886360545;64832;66921.31550791398;79920;16694.916525417295;16847.831521685115;17047.891887027305;17159.227834747217;17185.05482198491;17998.730019049464;18387.264509419474;18542.25830966746;18626.44120676382;18634.06829658539;16950.91524542419;17009.829901700825;17012.82987170162;17365.04492252938;17664.940103593824;16131.919340403223;16920.32318707238;17395.04327262013;17396.652066958337;17398.304067837144;17326.91336543309;17432.389866354617;17711.11444427748;17839.821601783817;18011.639767204844;9788.363756355815;11006.918062942388;11289.435528223392;11403.828942565702;12490.375481226085;10619.94690026545;11227.66317010491;11801.881981180079;12479.689632588448;13643.249621270925 11:11:43 INFO - PID 8440 | __end_tp_report 11:11:43 INFO - PID 8440 | __start_cc_report 11:11:43 INFO - PID 8440 | _x_x_mozilla_cycle_collect,480 11:11:43 INFO - PID 8440 | __end_cc_report 11:11:43 INFO - PID 8440 | __startTimestamp1497377503386__endTimestamp 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | ------- Summary: start ------- 11:11:43 INFO - PID 8440 | Number of tests: 6 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | [#0] dojo.html Cycles:165 Average:6120.97 Median:6346.40 stddev:783.93 (12.4%) stddev-sans-first:756.47 11:11:43 INFO - PID 8440 | Values: 3389.3 3462.9 3480.9 3483.9 3566.1 6126.9 6133.6 6151.9 6224.3 6268.9 6166.7 6212.7 6220.2 6223.0 6233.6 4886.1 5445.3 5573.7 5606.9 5620.8 5524.9 5532.9 5538.4 5566.1 5567.1 5103.1 5381.7 5400.3 5457.2 5485.3 5547.6 5550.5 5559.6 5571.7 5578.6 5378.9 5487.9 5510.3 5515.3 5526.7 6088.9 6218.8 6261.3 6274.3 6281.9 6439.5 6542.5 6549.3 6658.4 6745.0 6716.6 6723.1 6730.5 6774.7 6790.5 5404.9 5765.0 6295.7 6329.9 6332.5 6671.7 6770.6 6887.8 6920.9 6926.7 5404.7 6422.2 6479.4 6490.1 6593.7 6704.1 6789.9 6796.4 6803.0 6811.5 6550.3 6686.7 6692.2 6692.8 6707.2 5697.8 6632.1 6664.6 6690.2 6714.5 5238.9 5240.9 5277.2 5300.7 5322.8 2687.8 4364.9 4719.4 4738.0 4752.4 6239.3 6275.3 6351.1 6368.0 6379.2 6820.8 6827.0 6844.4 6868.6 6870.5 5322.4 6157.8 6301.7 6315.4 6349.9 5774.5 5835.3 5848.2 5890.0 6018.5 6283.7 6344.4 6701.4 6743.2 6795.5 6569.0 6732.9 6781.5 6801.3 6810.1 6953.0 6956.5 6962.1 6967.7 6974.0 6612.8 6663.0 6688.9 6693.8 6696.4 6551.8 6656.6 6776.8 6818.0 6826.7 6346.4 6424.9 6536.2 6542.3 6551.9 6500.6 6568.7 6579.7 6623.4 6633.9 5392.8 6264.9 6436.9 6437.5 6463.2 6330.5 6347.4 6380.3 6380.4 6408.1 5377.3 6339.8 6343.4 6345.2 6357.3 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | [#1] ext.html Cycles:180 Average:43102.42 Median:178431.26 stddev:75860.15 (42.5%) stddev-sans-first:76006.46 11:11:43 INFO - PID 8440 | Values: 795.5 805.5 807.8 831.7 841.5 37027.3 38075.0 38095.8 38103.9 38265.6 37813.6 38052.4 38054.8 38085.2 38130.8 7271.1 7374.9 7403.0 7403.0 7413.8 7262.8 7315.6 7344.2 7364.9 7371.5 7239.8 7274.1 7298.3 8008.6 8213.1 8209.3 8260.4 8274.5 8286.0 8293.6 7985.6 8011.0 8067.0 8092.0 8121.6 27060.6 35199.0 35255.6 35503.8 35531.8 3561.1 3601.1 3601.2 3602.7 3606.1 29595.0 29666.0 29667.4 29680.6 29736.4 3011.8 3024.8 3069.0 3095.7 3120.0 240841.0 242124.2 244222.0 299388.0 300456.0 46621.0 46953.0 47323.5 47355.5 47523.5 48675.0 48691.8 48710.0 48787.0 48849.5 14064.0 14195.1 14197.0 14219.4 14255.3 11652.2 11675.7 11681.2 11706.6 11709.3 11301.3 13263.0 13435.9 13524.7 13642.0 5467.6 5539.6 6031.4 6113.8 6132.9 916.7 976.6 988.0 1059.3 1064.4 20254.7 21434.7 22500.8 22772.1 23528.8 1088.7 1106.6 1117.6 1160.8 1162.6 11510.9 11579.2 11643.3 11657.4 12036.3 320990.0 321202.0 322646.0 323954.0 324409.0 52900.5 53192.0 53277.7 53303.0 53356.7 1501.3 1512.5 1531.1 1537.3 1540.9 19789.5 21444.9 22641.3 22734.4 23026.2 36828.3 36871.6 36934.4 36943.3 36968.0 273270.0 273733.0 274307.9 274497.0 274676.0 28047.6 28492.6 28522.6 32968.7 33733.7 40178.6 40659.6 41033.2 41268.6 41446.4 25194.9 27111.1 27170.5 27518.9 27971.4 28638.4 29458.1 29993.6 30109.0 30162.8 32453.5 35549.6 35596.8 35618.8 35715.8 24823.0 27299.0 28667.3 28711.0 29894.0 21970.7 22948.7 23524.8 23714.6 23724.6 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | [#2] jquery.html Cycles:180 Average:38051.91 Median:12157.09 stddev:95041.55 (781.8%) stddev-sans-first:95266.16 11:11:43 INFO - PID 8440 | Values: 415.5 487.6 489.8 496.9 501.6 22090.0 22922.1 22939.0 22992.7 23057.0 16374.3 17382.7 17403.4 17430.8 17749.6 6047.9 6243.4 6248.0 6250.2 6251.2 6051.7 6101.4 6164.7 6179.7 6191.4 6136.2 6141.8 6144.8 6146.4 6152.3 5522.6 5952.1 6137.8 6144.9 6145.7 4790.4 5636.1 5673.1 5685.3 5703.0 28804.4 29237.7 29271.9 29322.3 29355.7 1953.4 2196.8 2224.2 2238.6 2245.1 17195.0 17490.5 17596.8 18342.3 18431.9 1992.4 2172.8 2180.0 2195.3 2231.1 107897.0 158247.0 162707.0 165507.0 169032.0 10884.6 13685.5 14557.3 14734.5 14753.6 19688.5 19836.3 19871.7 19872.0 19906.7 8640.7 9346.0 9615.6 9714.7 9763.3 8872.0 8888.8 8909.6 8938.4 8974.9 7402.5 7490.6 8988.3 9049.2 9083.8 2602.7 2604.6 2619.1 2621.5 2742.8 857.3 914.6 923.5 960.4 988.4 20030.7 20047.5 20054.6 20451.9 20861.2 947.2 981.7 991.8 1005.5 1017.8 8328.7 10015.0 10268.4 10636.8 10914.0 548518.0 549993.0 553085.0 559016.4 559181.0 145169.0 150187.0 151155.2 165495.2 167836.0 1185.3 1208.5 1227.3 1233.8 1236.8 21918.3 22028.0 23242.2 23286.5 23768.3 72011.6 72705.0 74535.6 75149.6 75417.6 94771.2 95811.0 98505.5 99162.0 100489.5 10714.5 11342.4 11402.2 11434.7 11455.7 14382.6 17455.0 17539.0 17651.6 17712.3 12861.4 13958.4 14015.5 14120.4 14146.4 11316.2 12713.6 12779.9 13206.8 13349.9 14189.0 14218.9 14491.0 14791.7 14795.7 11047.9 11093.6 11245.3 11294.5 11317.0 12519.5 12868.0 14855.7 14882.9 15145.5 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | [#3] mootools.html Cycles:180 Average:8108.04 Median:2668.35 stddev:5313.46 (199.1%) stddev-sans-first:5304.65 11:11:43 INFO - PID 8440 | Values: 1426.9 1481.7 1501.1 1505.8 1517.9 12986.5 13475.1 13484.4 13501.4 13518.7 11161.7 11409.1 11467.8 11489.7 12617.3 2278.8 2638.2 2689.8 2709.9 2712.3 2650.1 2684.0 2690.2 2697.1 2711.3 2652.8 2661.1 2663.2 2664.9 2677.0 2221.8 2322.8 2551.6 2606.7 2618.0 2323.4 2325.0 2671.8 2769.3 2775.7 11884.8 12197.2 13073.0 13240.2 13241.5 5957.7 7469.0 7471.1 7476.0 7482.5 4354.7 4518.9 4619.0 4687.1 4691.2 1358.6 1362.4 1362.7 1381.7 1382.5 19505.5 19694.2 19706.4 19715.9 19754.5 6922.4 6959.0 6992.6 6997.6 7070.3 16127.8 16247.5 16344.4 16355.8 16358.8 7648.3 7655.8 7664.7 7679.0 7706.3 6389.6 6769.2 6771.3 6827.1 6844.6 5146.7 5299.7 5325.8 5333.3 5451.8 1615.5 1642.0 1649.0 1664.4 1669.7 810.5 826.3 848.5 849.7 865.2 6754.9 7139.4 7745.3 8065.4 8088.6 658.4 708.9 821.9 1007.0 1033.6 4758.4 4795.1 4842.1 4890.7 5687.4 17412.6 17978.4 17992.5 18126.5 18180.3 15985.0 17042.9 17053.1 17059.8 17400.4 8688.6 9164.1 9352.8 9356.9 9458.0 7288.6 7985.4 8055.3 8112.7 8249.5 12825.0 14040.9 15768.8 16112.9 16335.0 15386.8 15520.1 16096.8 16282.8 16821.4 10661.4 10923.7 10931.3 10956.7 10963.6 10406.7 12217.8 12388.3 12398.6 12420.8 9654.8 9839.3 10028.8 10124.9 10403.3 9438.3 9614.0 9951.2 10084.2 10273.6 8929.9 8992.2 8993.2 9095.0 9111.0 6194.3 6418.0 6433.2 6502.3 6922.8 6459.5 6529.5 6676.5 6749.4 7197.6 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | [#4] prototype.html Cycles:180 Average:5365.18 Median:5840.77 stddev:1647.45 (28.2%) stddev-sans-first:1608.84 11:11:43 INFO - PID 8440 | Values: 369.7 409.8 419.7 451.5 463.7 5794.3 5918.7 6354.3 6375.6 6436.6 5609.9 5635.5 5810.9 6066.7 6351.7 4419.5 4769.6 4907.2 4939.0 4953.2 4912.0 4942.3 4968.7 4988.3 4994.8 3774.0 4886.7 4889.7 4898.1 4901.7 4062.8 4243.3 4433.5 4688.3 4850.6 3417.6 3466.4 4040.8 4065.7 4075.7 4792.8 5049.0 5998.8 6104.0 6128.9 4371.8 5260.6 5517.4 5518.6 5524.6 5820.6 6661.8 6670.7 6741.3 6763.7 4437.4 4667.8 4687.3 4691.1 4692.8 6222.6 6941.2 7005.6 7076.4 7079.4 4183.7 4754.0 4757.5 4763.6 4773.9 4440.5 4829.4 4837.3 4846.4 4864.4 4922.8 5132.5 5558.5 5575.8 5576.0 6299.1 6698.7 6729.8 6736.2 6750.4 1412.6 1548.5 1668.5 1674.4 1675.0 945.6 1042.1 1115.0 1116.5 1121.5 5287.3 5327.7 5689.9 6061.8 6397.6 5097.8 5893.1 6156.6 6392.4 6400.0 5168.9 5603.9 6085.5 6331.5 6356.6 3333.5 3344.3 3616.1 3620.4 3654.4 5814.0 6716.8 6809.2 6865.6 7166.3 6381.9 7044.4 7110.6 7153.6 7234.2 5207.1 5860.9 6570.4 6751.5 7215.0 6904.0 6909.8 6933.0 6938.2 6964.9 6232.7 6793.0 6804.9 6823.8 6840.5 5588.2 7064.3 7113.8 7122.8 7133.2 4027.7 4313.6 4491.1 4731.0 4757.0 6007.7 6068.4 6595.7 6632.2 7033.6 6653.5 6672.9 6674.7 6696.2 6701.3 5759.7 6049.9 6541.7 6646.7 6651.9 6516.3 6559.9 6563.8 6567.1 6595.0 6086.5 6475.8 6644.9 6670.1 6689.9 5976.8 6478.1 6522.7 6524.6 6532.3 11:11:43 INFO - PID 8440 | 11:11:43 INFO - PID 8440 | [#5] yui.html Cycles:180 Average:24792.66 Median:17974.01 stddev:38193.79 (212.5%) stddev-sans-first:38258.30 11:11:43 INFO - PID 8440 | Values: 757.5 835.5 837.9 870.8 875.6 13554.7 16946.8 17078.8 17099.8 17767.6 12730.4 13176.6 13197.5 13229.3 13522.3 960.8 972.7 987.8 989.3 990.3 817.7 980.3 989.3 991.8 998.8 1043.9 1045.9 1048.4 1048.4 1050.7 949.9 1041.7 1058.0 1059.8 1065.6 651.6 774.2 774.3 774.7 776.0 16927.5 17490.6 17704.9 17706.3 17722.0 17285.5 17828.2 17855.9 17967.3 17980.7 12480.4 14206.3 14792.6 14911.2 14919.2 3604.3 3620.2 3630.4 3634.9 3641.1 114943.0 149092.3 149564.0 159107.2 160969.0 13670.8 13876.6 13983.9 14051.2 14225.0 23788.2 25963.1 26476.0 27015.2 27258.6 7967.0 8128.1 8154.5 8168.7 8179.5 3974.9 4030.6 4032.0 4033.0 4052.0 4494.9 4645.0 4724.7 4726.0 4733.1 880.2 960.4 966.2 984.3 985.9 638.6 640.2 642.2 670.6 675.8 10414.8 11762.1 12071.0 12115.9 12194.5 608.7 615.1 621.2 622.2 660.1 5492.7 5518.2 5570.4 5863.5 5878.0 136899.0 141643.9 142694.6 142847.3 144612.0 87109.5 115011.4 117469.4 119548.8 122234.8 77711.2 84651.0 96356.0 102034.0 103636.0 9576.4 10987.5 11706.6 11881.2 12325.4 42948.9 44316.1 45910.0 46173.8 47262.9 59089.7 63611.4 64832.0 66921.3 79920.0 16694.9 16847.8 17047.9 17159.2 17185.1 17998.7 18387.3 18542.3 18626.4 18634.1 16950.9 17009.8 17012.8 17365.0 17664.9 16131.9 16920.3 17395.0 17396.7 17398.3 17326.9 17432.4 17711.1 17839.8 18011.6 9788.4 11006.9 11289.4 11403.8 12490.4 10619.9 11227.7 11801.9 12479.7 13643.2 11:11:43 INFO - PID 8440 | -------- Summary: end -------- 11:11:43 INFO - PID 8440 | 11:11:44 INFO - TEST-INFO | 8440: exit 0 11:11:44 INFO - TEST-OK | dromaeo_css | took 1144794ms 11:11:44 INFO - TEST-START | kraken 11:11:44 INFO - operating with platform_type : mac_ 11:11:44 INFO - Initialising browser for kraken test... 11:11:44 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:52490/getInfo.html -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpRQoPfi/profile 11:11:44 INFO - TEST-INFO | started process 8447 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:52490/getInfo.html -foreground) 11:12:18 INFO - TEST-INFO | 8447: killed by SIGTERM 11:12:18 INFO - Browser initialized. 11:12:18 INFO - Running cycle 1/1 for kraken test... 11:12:18 INFO - TEST-INFO | started process 8452 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpRQoPfi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:19 INFO - PID 8452 | 2017-06-13 11:12:19.967 plugin-container[8453:164975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9847, name = 'com.apple.tsm.portname' 11:12:19 INFO - PID 8452 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:19 INFO - PID 8452 | 2017-06-13 11:12:19.967 plugin-container[8453:164975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 11:12:19 INFO - PID 8452 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:19 INFO - PID 8452 | 2017-06-13 11:12:19.968 plugin-container[8453:164975] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:12:20 INFO - PID 8452 | 2017-06-13 11:12:20.741 plugin-container[8454:165022] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 11:12:20 INFO - PID 8452 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:20 INFO - PID 8452 | 2017-06-13 11:12:20.741 plugin-container[8454:165022] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 11:12:20 INFO - PID 8452 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:20 INFO - PID 8452 | 2017-06-13 11:12:20.741 plugin-container[8454:165022] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:12:30 INFO - PID 8452 | RSS: Main: 252694528 11:12:30 INFO - PID 8452 | 11:12:33 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-beat-detection) 11:12:33 INFO - PID 8452 | RSS: Main: 264577024 11:12:33 INFO - PID 8452 | 11:12:36 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-dft) 11:12:37 INFO - PID 8452 | RSS: Main: 274276352 11:12:37 INFO - PID 8452 | 11:12:40 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-fft) 11:12:41 INFO - PID 8452 | RSS: Main: 276463616 11:12:41 INFO - PID 8452 | 11:12:43 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-oscillator) 11:12:44 INFO - PID 8452 | RSS: Main: 278978560 11:12:44 INFO - PID 8452 | 11:12:46 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:12:46 INFO - PID 8452 | RSS: Main: 279019520 11:12:46 INFO - PID 8452 | 11:12:50 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-darkroom) 11:12:50 INFO - PID 8452 | RSS: Main: 284127232 11:12:50 INFO - PID 8452 | 11:12:53 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-desaturate) 11:12:54 INFO - PID 8452 | RSS: Main: 283803648 11:12:54 INFO - PID 8452 | 11:12:57 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52490/tests/kraken/driver.html?testName=json-parse-financial) 11:12:58 INFO - PID 8452 | RSS: Main: 284475392 11:12:58 INFO - PID 8452 | 11:12:59 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:13:00 INFO - PID 8452 | RSS: Main: 283664384 11:13:00 INFO - PID 8452 | 11:13:02 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:13:03 INFO - PID 8452 | RSS: Main: 283463680 11:13:03 INFO - PID 8452 | 11:13:05 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:13:05 INFO - PID 8452 | RSS: Main: 283987968 11:13:05 INFO - PID 8452 | 11:13:08 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:13:09 INFO - PID 8452 | RSS: Main: 283648000 11:13:09 INFO - PID 8452 | 11:13:12 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:13:13 INFO - PID 8452 | RSS: Main: 283836416 11:13:13 INFO - PID 8452 | 11:13:14 INFO - PID 8452 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52490/tests/kraken/driver.html?testName=ai-astar) 11:13:15 INFO - PID 8452 | RSS: Main: 285143040 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | __start_tp_report 11:13:15 INFO - PID 8452 | _x_x_mozilla_page_load 11:13:15 INFO - PID 8452 | _x_x_mozilla_page_load_details 11:13:15 INFO - PID 8452 | |i|pagename|runs| 11:13:15 INFO - PID 8452 | |0;ai-astar;105;117;116;141;118;165;172;97;109;158 11:13:15 INFO - PID 8452 | |1;audio-beat-detection;140;118;129;151;156;125;150;192;162;101 11:13:15 INFO - PID 8452 | |2;audio-dft;202;201;239;249;274;224;283;279;205;204 11:13:15 INFO - PID 8452 | |3;audio-fft;69;78;85;133;61;63;73;76;102;79 11:13:15 INFO - PID 8452 | |4;audio-oscillator;71;67;105;62;100;114;144;55;93;149 11:13:15 INFO - PID 8452 | |5;imaging-gaussian-blur;103;136;137;94;91;116;90;90;78;134 11:13:15 INFO - PID 8452 | |6;imaging-darkroom;97;98;128;97;96;97;101;97;109;140 11:13:15 INFO - PID 8452 | |7;imaging-desaturate;91;94;88;93;117;84;96;149;144;94 11:13:15 INFO - PID 8452 | |8;json-parse-financial;51;52;53;55;53;53;54;54;53;53 11:13:15 INFO - PID 8452 | |9;json-stringify-tinderbox;58;58;62;73;65;58;61;57;59;56 11:13:15 INFO - PID 8452 | |10;stanford-crypto-aes;83;78;78;84;80;81;81;82;81;81 11:13:15 INFO - PID 8452 | |11;stanford-crypto-ccm;129;152;144;132;150;154;159;144;140;172 11:13:15 INFO - PID 8452 | |12;stanford-crypto-pbkdf2;183;202;219;185;218;236;189;178;177;177 11:13:15 INFO - PID 8452 | |13;stanford-crypto-sha256-iterative;70;82;74;66;67;71;67;67;66;69 11:13:15 INFO - PID 8452 | __end_tp_report 11:13:15 INFO - PID 8452 | __start_cc_report 11:13:15 INFO - PID 8452 | _x_x_mozilla_cycle_collect,1190 11:13:15 INFO - PID 8452 | __end_cc_report 11:13:15 INFO - PID 8452 | __startTimestamp1497377595079__endTimestamp 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | ------- Summary: start ------- 11:13:15 INFO - PID 8452 | Number of tests: 14 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#0] ai-astar Cycles:10 Average:129.80 Median:149.50 stddev:26.99 (18.1%) stddev-sans-first:27.10 11:13:15 INFO - PID 8452 | Values: 105.0 117.0 116.0 141.0 118.0 165.0 172.0 97.0 109.0 158.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:150.50 stddev:25.73 (17.1%) stddev-sans-first:27.28 11:13:15 INFO - PID 8452 | Values: 140.0 118.0 129.0 151.0 156.0 125.0 150.0 192.0 162.0 101.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#2] audio-dft Cycles:10 Average:236.00 Median:244.00 stddev:33.65 (13.8%) stddev-sans-first:33.36 11:13:15 INFO - PID 8452 | Values: 202.0 201.0 239.0 249.0 274.0 224.0 283.0 279.0 205.0 204.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#3] audio-fft Cycles:10 Average:81.90 Median:74.50 stddev:21.40 (28.7%) stddev-sans-first:22.19 11:13:15 INFO - PID 8452 | Values: 69.0 78.0 85.0 133.0 61.0 63.0 73.0 76.0 102.0 79.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:58.50 stddev:33.08 (56.5%) stddev-sans-first:33.82 11:13:15 INFO - PID 8452 | Values: 71.0 67.0 105.0 62.0 100.0 114.0 144.0 55.0 93.0 149.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#5] imaging-gaussian-blur Cycles:10 Average:106.90 Median:84.00 stddev:22.14 (26.4%) stddev-sans-first:23.44 11:13:15 INFO - PID 8452 | Values: 103.0 136.0 137.0 94.0 91.0 116.0 90.0 90.0 78.0 134.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:97.00 stddev:15.50 (16.0%) stddev-sans-first:16.09 11:13:15 INFO - PID 8452 | Values: 97.0 98.0 128.0 97.0 96.0 97.0 101.0 97.0 109.0 140.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#7] imaging-desaturate Cycles:10 Average:105.00 Median:92.00 stddev:23.56 (25.6%) stddev-sans-first:24.43 11:13:15 INFO - PID 8452 | Values: 91.0 94.0 88.0 93.0 117.0 84.0 96.0 149.0 144.0 94.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:1.10 (2.1%) stddev-sans-first:0.87 11:13:15 INFO - PID 8452 | Values: 51.0 52.0 53.0 55.0 53.0 53.0 54.0 54.0 53.0 53.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:60.00 stddev:5.08 (8.5%) stddev-sans-first:5.29 11:13:15 INFO - PID 8452 | Values: 58.0 58.0 62.0 73.0 65.0 58.0 61.0 57.0 59.0 56.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.91 (2.4%) stddev-sans-first:1.87 11:13:15 INFO - PID 8452 | Values: 83.0 78.0 78.0 84.0 80.0 81.0 81.0 82.0 81.0 81.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:151.00 stddev:12.76 (8.4%) stddev-sans-first:11.62 11:13:15 INFO - PID 8452 | Values: 129.0 152.0 144.0 132.0 150.0 154.0 159.0 144.0 140.0 172.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.40 Median:195.50 stddev:21.17 (10.8%) stddev-sans-first:21.89 11:13:15 INFO - PID 8452 | Values: 183.0 202.0 219.0 185.0 218.0 236.0 189.0 178.0 177.0 177.0 11:13:15 INFO - PID 8452 | 11:13:15 INFO - PID 8452 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:69.50 stddev:4.95 (7.1%) stddev-sans-first:5.25 11:13:15 INFO - PID 8452 | Values: 70.0 82.0 74.0 66.0 67.0 71.0 67.0 67.0 66.0 69.0 11:13:15 INFO - PID 8452 | -------- Summary: end -------- 11:13:15 INFO - PID 8452 | 11:13:15 INFO - TEST-INFO | 8452: exit 0 11:13:16 INFO - TEST-OK | kraken | took 91912ms 11:13:16 INFO - SUITE-END | took 1236s 11:13:16 INFO - Completed test suite (00:20:37) 11:13:16 INFO - javascript benchmark 11:13:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8126.804181892596, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.271306669066, 3462.913427164322, 3480.8607655693772, 3483.912902177444, 3566.1084728817814, 6126.853582554517, 6133.61010758268, 6151.923413402647, 6224.28420731616, 6268.905966410507, 6166.722497487613, 6212.658303793301, 6220.222472190976, 6222.97320942044, 6233.563650544464, 4886.1204983103025, 5445.292112025431, 5573.693446860421, 5606.859828504279, 5620.803271885485, 5524.944750552483, 5532.889342213153, 5538.390777014525, 5566.137248726433, 5567.081431563787, 5103.081445339819, 5381.650192737479, 5400.297961265011, 5457.208704737831, 5485.286912701363, 5547.55619550435, 5550.47270509301, 5559.555235581184, 5571.749271282802, 5578.609497335188, 5378.91931621026, 5487.862803429906, 5510.256115424407, 5515.338159420855, 5526.696031718254, 6088.908666370008, 6218.81343559694, 6261.311255761862, 6274.341194174592, 6281.87436251277, 6439.452646525051, 6542.476601871839, 6549.312322206148, 6658.3674550917585, 6745.0, 6716.597004179765, 6723.058771771955, 6730.461563074942, 6774.661266936633, 6790.456763458911, 5404.891902161935, 5764.971175144097, 6295.685215739286, 6329.905051424233, 6332.461740751995, 6671.699773510204, 6770.627615481196, 6887.758928437581, 6920.930790692129, 6926.653667316614, 5404.729763511808, 6422.165118534654, 6479.3520647935775, 6490.0589414534625, 6593.703283352261, 6704.061431399608, 6789.864202715918, 6796.388325050769, 6803.0, 6811.523193376515, 6550.312217217219, 6686.665666716644, 6692.163479565054, 6692.83267918306, 6707.195136583641, 5697.772089116389, 6632.137822083195, 6664.600123992576, 6690.163729533809, 6714.496412768965, 5238.92141617876, 5240.921386179134, 5277.155655095166, 5300.708461034603, 5322.813701520506, 2687.784977201819, 4364.861218003219, 4719.433667959866, 4737.976310118427, 4752.358431611758, 6239.313675495692, 6275.309715931244, 6351.110844481776, 6367.968160159169, 6379.170707807955, 6820.829479263058, 6827.044213810071, 6844.384005439534, 6868.553544019623, 6870.484713646497, 5322.361316642027, 6157.753689852445, 6301.6534090625455, 6315.400036996478, 6349.904751428732, 5774.509166720792, 5835.270591176103, 5848.152017957457, 5889.970550147222, 6018.518518518508, 6283.685815709287, 6344.429001389897, 6701.396874281338, 6743.157105361928, 6795.45636349081, 6568.967155164193, 6732.899006515004, 6781.491388145811, 6801.251862295243, 6810.114685090906, 6953.0, 6956.513044087068, 6962.060121883481, 6967.651617419007, 6973.965130174317, 6612.8346791330605, 6662.966685166543, 6688.899666505104, 6693.832654183684, 6696.430803381767, 6551.80344589663, 6656.633885136364, 6776.7628133015105, 6818.0, 6826.726930922906, 6346.397092276226, 6424.9036264455135, 6536.215654121535, 6542.313057128933, 6551.868962620816, 6500.577462464925, 6568.671566421564, 6579.703913324008, 6623.4038936495945, 6633.867322653617, 5392.784288628411, 6264.937350626436, 6436.935630643634, 6437.452816510649, 6463.192100987377, 6330.461910737455, 6347.428731414195, 6380.298167201697, 6380.3619638035825, 6408.102865598913, 5377.327834020747, 6339.77810776639, 6343.429091381613, 6345.17512723343, 6357.300696923242], "value": 6066.227913524372, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.5107608820573, 805.5005896344268, 807.8343939492405, 831.7005877883961, 841.5161282262701, 37027.25945481089, 38075.0, 38095.809520952425, 38103.85688429345, 38265.617343826554, 37813.62186378135, 38052.42921356175, 38054.8097259514, 38085.23829523415, 38130.8093459533, 7271.12746470423, 7374.852502949938, 7403.0, 7403.037605111328, 7413.777586672409, 7262.818429539251, 7315.56106633601, 7344.2288559701265, 7364.889526657104, 7371.483996120273, 7239.782806515813, 7274.054372931511, 7298.343149116578, 8008.63961121748, 8213.137620549845, 8209.261166495015, 8260.380471464665, 8274.544900030556, 8286.0, 8293.626786794579, 7985.600719964036, 8011.0, 8067.0, 8092.0, 8121.593920303962, 27060.59409108865, 35199.0, 35255.64744352549, 35503.82248088769, 35531.75638852628, 3561.1453251219523, 3601.099725068733, 3601.1897323102353, 3602.7478076534653, 3606.1165014571598, 29594.964176253943, 29666.0, 29667.40665186706, 29680.554791678143, 29736.40527189444, 3011.804232724866, 3024.773142014336, 3069.0, 3095.7059079387423, 3119.9688003119904, 240841.0, 242124.15751685045, 244222.0, 299388.0, 300456.0, 46621.0, 46953.0, 47323.52676473191, 47355.5264447358, 47523.524764751906, 48675.0, 48691.78270543194, 48710.0, 48787.0, 48849.51150488521, 14064.015518913578, 14195.077319974169, 14197.006209565438, 14219.431222751182, 14255.287235638176, 11652.184347095792, 11675.708107297385, 11681.182317237712, 11706.590269340531, 11709.297442153498, 11301.26541774782, 13263.0, 13435.865641343657, 13524.729505409836, 13641.976851736159, 5467.644603100785, 5539.584531160179, 6031.366706495843, 6113.786017489367, 6132.877342453126, 916.7433118726767, 976.6288810252058, 988.0377214801905, 1059.2902755154066, 1064.387976913278, 20254.69617955732, 21434.678479822815, 22500.774992250197, 22772.089116435156, 23528.82355882199, 1088.733260351219, 1106.6458733205297, 1117.5585643670868, 1160.8142697168412, 1162.5698491558007, 11510.884891151149, 11579.189456737948, 11643.301401915744, 11657.417129143509, 12036.277823330629, 320990.0, 321202.0, 322646.0, 323954.0, 324409.0, 52900.47099528956, 53192.0, 53277.668058298856, 53303.0, 53356.73321633367, 1501.2868887278455, 1512.455516014234, 1531.0660497096762, 1537.3235776258412, 1540.8536189062245, 19789.50526236798, 21444.88820686097, 22641.320760377213, 22734.431639209153, 23026.194083207007, 36828.26343473169, 36871.63128368682, 36934.445983309735, 36943.261134776614, 36968.0, 273270.0, 273733.0, 274307.88538171543, 274497.0, 274676.0, 28047.57928631113, 28492.57261141126, 28522.57216141718, 32968.67031329656, 33733.662663373056, 40178.59821401749, 40659.59340406558, 41033.179336412504, 41268.587314126475, 41446.378304324855, 25194.874025629753, 27111.051113210942, 27170.456590868467, 27518.899244030014, 27971.44057118887, 28638.42068921023, 29458.116256511483, 29993.55009674813, 30109.0, 30162.793488260224, 32453.51319730253, 35549.64450355567, 35596.82201588975, 35618.821905890305, 35715.82142089273, 24823.0, 27299.0, 28667.28331791722, 28711.0, 29893.95371161999, 21970.670439943733, 22948.655770163794, 23524.76475235294, 23714.644280335462, 23724.644130337714], "value": 16702.09310971876, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.49102349621734, 487.6050399176665, 489.8383533433967, 496.9043259612555, 501.60373305088984, 22090.0, 22922.083116675356, 22938.967746451428, 22992.65511017336, 23057.0, 16374.345026198967, 17382.739258911126, 17403.390881319156, 17430.825691743077, 17749.64500709985, 6047.87904241916, 6243.406876346739, 6247.9687601562155, 6250.187475628162, 6251.1560939273295, 6051.727672254737, 6101.389861013908, 6164.660943648098, 6179.721912513947, 6191.411815877507, 6136.171616831715, 6141.815745527641, 6144.84637884052, 6146.416090471421, 6152.323244443108, 5522.6134170608075, 5952.107183922403, 6137.785177518787, 6144.877102457948, 6145.692715364236, 4790.353302304214, 5636.126400407943, 5673.063944449161, 5685.289338832646, 5702.971485142548, 28804.423911521862, 29237.684304206363, 29271.85364073187, 29322.266943326373, 29355.706442935512, 1953.433504283756, 2196.7803219678062, 2224.177054489849, 2238.597052530544, 2245.0682966569016, 17195.0, 17490.513306368975, 17596.824031759774, 18342.26630934761, 18431.907840460713, 1992.4122383896786, 2172.8044475997235, 2179.9891000544894, 2195.3194509702043, 2231.0852550454397, 107897.0, 158247.0, 162707.0, 165507.0, 169032.0, 10884.564617415374, 13685.452581896812, 14557.344919478648, 14734.484293049692, 14753.557393278219, 19688.507787305432, 19836.305729299405, 19871.701924471145, 19872.0, 19906.701399479018, 8640.740777776677, 9345.953270233742, 9615.615375384907, 9714.659986900566, 9763.316567840184, 8871.955640221757, 8888.777780555538, 8909.643614255358, 8938.37431379797, 8974.910250897537, 7402.518836275625, 7490.625468726542, 8988.325875559229, 9049.185573298435, 9083.772905677279, 2602.7267136950727, 2604.5832666773226, 2619.0702300682983, 2621.5281249375294, 2742.8491432970977, 857.2713193785232, 914.5884352041608, 923.533615524156, 960.3565611040619, 988.3526290279879, 20030.696469906146, 20047.498812529804, 20054.59890802176, 20451.897740511202, 20861.165553378, 947.166493485742, 981.7398389426808, 991.7520619845038, 1005.4872015272117, 1017.7760892603616, 8328.708495202638, 10015.0, 10268.435236062089, 10636.787264254672, 10914.0, 548518.0, 549993.0, 553085.0, 559016.4098358965, 559181.0, 145169.0, 150187.0, 151155.24422378038, 165495.1725241366, 167836.0, 1185.2829038431796, 1208.474313673555, 1227.2881728597392, 1233.802591585342, 1236.7650146472142, 21918.342449726533, 22028.0, 23242.1865234716, 23286.534269314856, 23768.286951391485, 72011.63994179995, 72705.0, 74535.62732186304, 75149.62425187838, 75417.62291188509, 94771.15686529415, 95811.0, 98505.52241716237, 99162.0, 100489.47307898136, 10714.464276786286, 11342.432878356214, 11402.20184587104, 11434.71413214643, 11455.656330310105, 14382.568522944328, 17455.03997280111, 17539.0, 17651.55871103233, 17712.291508339524, 12861.421236044587, 13958.441662333393, 14015.509457169357, 14120.435182592992, 14146.43414263459, 11316.20786544934, 12713.618591442271, 12779.936100319439, 13206.80189797173, 13349.866501334862, 14189.0, 14218.862491000902, 14490.985631005728, 14791.704165916406, 14795.704085918436, 11047.895210478888, 11093.611723589636, 11245.269057511236, 11294.548218071184, 11317.038051765692, 12519.499220031461, 12868.0, 14855.702885942004, 14882.925585372004, 15145.469908553148], "value": 11243.817393047577, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.9001169918106, 1481.7406953783086, 1501.0693370110532, 1505.8104097762769, 1517.9468718594844, 12986.54547090852, 13475.124116932404, 13484.393202305895, 13501.392437340319, 13518.729625407486, 11161.72095697606, 11409.08727301816, 11467.827982580247, 11489.712757181074, 12617.30604816737, 2278.7949084582383, 2638.195350418117, 2689.7713694336003, 2709.878055487498, 2712.3354778079397, 2650.09896635144, 2683.9597406038924, 2690.1794952539467, 2697.136916186821, 2711.267957651433, 2652.774514166298, 2661.095227622608, 2663.227663977444, 2664.866756662169, 2676.986615066922, 2221.7889300516426, 2322.814174866007, 2551.591745320749, 2606.700229473606, 2617.973820261792, 2323.430759463942, 2325.0351104291867, 2671.839689618629, 2769.2799872033415, 2775.680796708384, 11884.821727674092, 12197.207181533173, 13073.0, 13240.20558766477, 13241.470341186341, 5957.672328021957, 7468.991686122358, 7471.140818805852, 7476.0, 7482.4762266641355, 4354.6516278697945, 4518.864434066983, 4618.953810461886, 4687.1328804171335, 4691.249400096002, 1358.5788405594296, 1362.434589645296, 1362.693393986355, 1381.6822130909948, 1382.5092092307286, 19505.512362190915, 19694.212231510723, 19706.408807735646, 19715.901420492948, 19754.50613734668, 6922.44245044871, 6959.0257363969085, 6992.61540615271, 6997.615131167733, 7070.328318809705, 16127.75808362852, 16247.512574622544, 16344.427945021867, 16355.754663680054, 16358.754618680729, 7648.349890259279, 7655.770326890202, 7664.6934122635585, 7678.961605191938, 7706.267904549059, 6389.584676995981, 6769.1876974763345, 6771.28901465339, 6827.112475378169, 6844.623545704934, 5146.665466744649, 5299.655522391032, 5325.760340784674, 5333.253344531769, 5451.754671039813, 1615.4668959243422, 1641.9917900410421, 1649.043554738249, 1664.4091347571439, 1669.699454098274, 810.528890064531, 826.2563692676639, 848.5078654380444, 849.7195925344621, 865.1787986288897, 6754.8854439018, 7139.393151582174, 7745.341645960044, 8065.435419520694, 8088.595570221465, 658.3778329478597, 708.8688592610383, 821.9342452603777, 1006.9949650251701, 1033.5762337441613, 4758.428988521399, 4795.136875362468, 4842.080004799077, 4890.706557606484, 5687.431256874363, 17412.564685882702, 17978.370757023702, 17992.460226193234, 18126.456206313833, 18180.272789088554, 15985.0, 17042.914785425994, 17053.06208158563, 17059.829401706073, 17400.39098631542, 8688.565571721389, 9164.083591640783, 9352.812943741086, 9356.90643093574, 9458.0, 7288.599127048064, 7985.441019128605, 8055.275025247756, 8112.675492980215, 8249.463784853966, 12825.0, 14040.929795350958, 15768.842311576738, 16112.919435402748, 16335.0, 15386.769198462256, 15520.068795872285, 16096.758548621547, 16282.755758663867, 16821.41125060617, 10661.41362225054, 10923.672289831318, 10931.289466184673, 10956.726081848017, 10963.616273430393, 10406.687799366031, 12217.816732748837, 12388.318642474751, 12398.5660501882, 12420.813687794509, 9654.758631034, 9839.311248212556, 10028.799424011624, 10124.898751012395, 10403.323783954018, 9438.292128090283, 9613.951930240584, 9951.203903687543, 10084.243681723754, 10273.640422585173, 8929.866052009354, 8992.190702836777, 8993.190612844613, 9095.04502027304, 9111.0, 6194.318624951162, 6418.032222866585, 6433.163688720437, 6502.284748677547, 6922.757703480354, 6459.483241340776, 6529.539344147609, 6676.499262555425, 6749.3925546699065, 7197.568145911262], "value": 6018.388939031057, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.68022660398765, 409.83606557377067, 419.71669123341735, 451.503346319049, 463.7055469776692, 5794.275715535558, 5918.727473593171, 6354.3010268870385, 6375.61746295222, 6436.645984470852, 5609.931486327227, 5635.520980716634, 5810.937117323353, 6066.726997285122, 6351.7459301628, 4419.491758447772, 4769.642276829234, 4907.239377896422, 4938.950610493885, 4953.182724850395, 4912.0, 4942.308076869242, 4968.701877887338, 4988.301637770697, 4994.775235114428, 3773.9811300943456, 4886.682365646239, 4889.657723959324, 4898.093852637256, 4901.68139070961, 4062.79686015701, 4243.29985552385, 4433.467983841927, 4688.320193571913, 4850.587700045483, 3417.6411476795074, 3466.3587236361354, 4040.7575545467366, 4065.6747460203114, 4075.714699971003, 4792.849716068118, 5048.974755126201, 5998.7600495980105, 6104.0, 6128.938710612882, 4371.846985355497, 5260.579153667697, 5517.36550296717, 5518.586106042023, 5524.640898341595, 5820.621659592113, 6661.800145995579, 6670.666466676646, 6741.325867413219, 6763.661816909184, 4437.356583295436, 4667.766611669403, 4687.273472611751, 4691.085238378518, 4692.8122875084955, 6222.595531290452, 6941.236463988957, 7005.579665220103, 7076.363127318566, 7079.433645308363, 4183.728057676241, 4754.0, 4757.500462451462, 4763.642726795504, 4773.904521909542, 4440.511543730187, 4829.420469543677, 4837.298591704183, 4846.3699719037, 4864.36763220779, 4922.75386230687, 5132.538071573576, 5558.471945165203, 5575.832725018256, 5576.0, 6299.149614802035, 6698.698558564876, 6729.798106056826, 6736.191657001191, 6750.426213771884, 1412.6468382904275, 1548.5276990517775, 1668.5244705258942, 1674.4055860169708, 1674.9916250418669, 945.5650400815564, 1042.1241764823287, 1114.9944250278697, 1116.5310569560804, 1121.5009320852305, 5287.3126493556365, 5327.7336133193185, 5689.862027594498, 6061.787642471523, 6397.552171347961, 5097.776533631902, 5893.086571581412, 6156.5998210116195, 6392.4246817786625, 6400.0, 5168.862850172956, 5603.8599035023635, 6085.5435842312, 6331.493480521547, 6356.5697718014, 3333.4999750037205, 3344.2809795894, 3616.132128289244, 3620.3845346291196, 3654.415293553072, 5814.0, 6716.775947545108, 6809.216940051859, 6865.5537390069485, 7166.283371662897, 6381.904271435932, 7044.436445084381, 7110.555902560899, 7153.570785752871, 7234.240404757425, 5207.062728708869, 5860.912086318624, 6570.441512471493, 6751.459883209233, 7215.0, 6904.0, 6909.792706218822, 6932.97887225398, 6938.202106757787, 6964.860702785814, 6232.688365581612, 6793.015012823014, 6804.9319506804295, 6823.829404264933, 6840.452763778786, 5588.217649529151, 7064.32888875556, 7113.786586402416, 7122.786316410516, 7133.2153463117975, 4027.7180597359074, 4313.590208930146, 4491.079328737535, 4731.0, 4757.001029783784, 6007.699615019144, 6068.423499767515, 6595.703193356407, 6632.204135503577, 7033.577985320897, 6653.500987425866, 6672.931391268722, 6674.733010679518, 6696.192028152469, 6701.3298670134545, 5759.65442073477, 6049.911016017159, 6541.672916354259, 6646.667666616747, 6651.933480665131, 6516.283208847118, 6559.868802624016, 6563.750943283106, 6567.140316165907, 6595.0, 6086.543509236736, 6475.834349817078, 6644.933550664625, 6670.097992674146, 6689.866202675822, 5976.820695379146, 6478.060681201106, 6522.706478208397, 6524.641144737085, 6532.281449040697], "value": 4862.180172990282, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.4962649837857, 835.527926721402, 837.932965362771, 870.7982983482793, 875.5578432891389, 13554.728905421885, 16946.83053169468, 17078.829211707878, 17099.82900170998, 17767.55581110473, 12730.363481825918, 13176.60470185896, 13197.538086166986, 13229.272390018545, 13522.323883805819, 960.8078384323128, 972.65470757881, 987.7876256604828, 989.2975987049203, 990.2919412619975, 817.6811043692965, 980.289290264556, 989.3470309595668, 991.7966816802539, 998.8351921932872, 1043.8956104389576, 1045.8901815309398, 1048.3866937841372, 1048.391932679048, 1050.726811029134, 949.9002604726508, 1041.671873359893, 1057.9947100264487, 1059.8039362717846, 1065.5844220753913, 651.5894986158689, 774.1948373691354, 774.3031271855319, 774.740461945249, 775.9718373155591, 16927.49217523476, 17490.562735931577, 17704.91147544254, 17706.29174833005, 17722.025288609122, 17285.481435556954, 17828.197731102133, 17855.910720446445, 17967.281308747635, 17980.730289045674, 12480.438380272819, 14206.289685515683, 14792.63018424548, 14911.179885106314, 14919.179445130514, 3604.2611264690677, 3620.167361506868, 3630.4191329387436, 3634.854605815764, 3641.107928557494, 114943.0, 149092.2545387277, 149564.0, 159107.2044639781, 160969.0, 13670.794938075936, 13876.58370248894, 13983.860161398256, 14051.156930583993, 14225.0, 23788.167414140422, 25963.091291804696, 26476.0, 27015.189544313307, 27258.5911211332, 7967.00412448444, 8128.105908350077, 8154.469959452736, 8168.673253069929, 8179.468334558234, 3974.8608798691907, 4030.5969403060044, 4031.959680403217, 4033.032072302626, 4051.9797401012806, 4494.910101797946, 4644.976775116171, 4724.71651700899, 4726.0, 4733.14803335396, 880.2341962492673, 960.4429430930044, 966.1594412860856, 984.3306551544875, 985.8718366612294, 638.6327861479667, 640.1677818835495, 642.2036674523557, 670.6345041952147, 675.8141511084491, 10414.791704165875, 11762.117841161777, 12070.973967212885, 12115.878841211475, 12194.451249693608, 608.6986941464004, 615.1203778596561, 621.15522888872, 622.2222222222222, 660.085781193051, 5492.69790161537, 5518.19986102013, 5570.442955704397, 5863.472287494146, 5877.970610147007, 136899.0, 141643.91780410937, 142694.5730542702, 142847.28576357051, 144612.0, 87109.51561937579, 115011.42494287476, 117469.4126529379, 119548.8045119555, 122234.77765222234, 77711.2228877704, 84651.0, 96356.0, 102034.0, 103636.0, 9576.425414475154, 10987.506930881149, 11706.648800535997, 11881.22772019816, 12325.383730813244, 42948.926276843325, 44316.11367772691, 45910.0, 46173.76913115413, 47262.87317070671, 59089.70455147697, 63611.363886360545, 64832.0, 66921.31550791398, 79920.0, 16694.916525417295, 16847.831521685115, 17047.891887027305, 17159.227834747217, 17185.05482198491, 17998.730019049464, 18387.264509419474, 18542.25830966746, 18626.44120676382, 18634.06829658539, 16950.91524542419, 17009.829901700825, 17012.82987170162, 17365.04492252938, 17664.940103593824, 16131.919340403223, 16920.32318707238, 17395.04327262013, 17396.652066958337, 17398.304067837144, 17326.91336543309, 17432.389866354617, 17711.11444427748, 17839.821601783817, 18011.639767204844, 9788.363756355815, 11006.918062942388, 11289.435528223392, 11403.828942565702, 12490.375481226085, 10619.94690026545, 11227.66317010491, 11801.881981180079, 12479.689632588448, 13643.249621270925], "value": 8641.253615018306, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1612.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 116.0, 141.0, 118.0, 165.0, 172.0, 97.0, 109.0, 158.0], "value": 129.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 118.0, 129.0, 151.0, 156.0, 125.0, 150.0, 192.0, 162.0, 101.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 201.0, 239.0, 249.0, 274.0, 224.0, 283.0, 279.0, 205.0, 204.0], "value": 236.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 85.0, 133.0, 61.0, 63.0, 73.0, 76.0, 102.0, 79.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 105.0, 62.0, 100.0, 114.0, 144.0, 55.0, 93.0, 149.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 136.0, 137.0, 94.0, 91.0, 116.0, 90.0, 90.0, 78.0, 134.0], "value": 106.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 128.0, 97.0, 96.0, 97.0, 101.0, 97.0, 109.0, 140.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 88.0, 93.0, 117.0, 84.0, 96.0, 149.0, 144.0, 94.0], "value": 105.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 53.0, 55.0, 53.0, 53.0, 54.0, 54.0, 53.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 62.0, 73.0, 65.0, 58.0, 61.0, 57.0, 59.0, 56.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 78.0, 84.0, 80.0, 81.0, 81.0, 82.0, 81.0, 81.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 152.0, 144.0, 132.0, 150.0, 154.0, 159.0, 144.0, 140.0, 172.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 202.0, 219.0, 185.0, 218.0, 236.0, 189.0, 178.0, 177.0, 177.0], "value": 196.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 74.0, 66.0, 67.0, 71.0, 67.0, 67.0, 66.0, 69.0], "value": 69.9, "unit": "score"}]}]} 11:13:16 INFO - Return code: 0 11:13:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:13:16 INFO - # TBPL SUCCESS # 11:13:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:16 INFO - 2017-06-13 11:13:16.479 screenresolution[8460:165416] starting screenresolution argv=screenresolution get 11:13:16 INFO - 2017-06-13 11:13:16.482 screenresolution[8460:165416] Display 0: 1600x1200x32@60 11:13:16 INFO - 2017-06-13 11:13:16.488 screenresolution[8461:165418] starting screenresolution argv=screenresolution list 11:13:16 INFO - Available Modes on Display 0 11:13:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:13:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:13:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:13:16 INFO - Intel Iris: 11:13:16 INFO - Chipset Model: Intel Iris 11:13:16 INFO - Type: GPU 11:13:16 INFO - Bus: Built-In 11:13:16 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:16 INFO - Vendor: Intel (0x8086) 11:13:16 INFO - Device ID: 0x0a2e 11:13:16 INFO - Revision ID: 0x0009 11:13:16 INFO - Displays: 11:13:16 INFO - Display: 11:13:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:13:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:16 INFO - Display Serial Number: 1600x1200 60 11:13:16 INFO - Main Display: Yes 11:13:16 INFO - Mirror: Off 11:13:16 INFO - Online: Yes 11:13:16 INFO - Rotation: Supported 11:13:16 INFO - Return code: 0 11:13:16 INFO - Running post-action listener: _resource_record_post_action 11:13:16 INFO - [mozharness: 2017-06-13 18:13:16.728604Z] Finished run-tests step (success) 11:13:16 INFO - Running post-run listener: _resource_record_post_run 11:13:16 INFO - Total resource usage - Wall time: 1260s; CPU: 53.0%; Read bytes: 1328640; Write bytes: 578284032; Read time: 17; Write time: 2112 11:13:16 INFO - TinderboxPrint: CPU usage
53.4% 11:13:16 INFO - TinderboxPrint: I/O read bytes / time
1,328,640 / 17 11:13:16 INFO - TinderboxPrint: I/O write bytes / time
578,284,032 / 2,112 11:13:16 INFO - TinderboxPrint: CPU idle
2,348.0 (46.6%) 11:13:16 INFO - TinderboxPrint: CPU system
1,029.5 (20.4%) 11:13:16 INFO - TinderboxPrint: CPU user
1,660.3 (33.0%) 11:13:16 INFO - TinderboxPrint: Swap in / out
252,956,672 / 0 11:13:16 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 247763456; Write bytes: 246345728; Read time: 17791; Write time: 511 11:13:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:13:16 INFO - run-tests - Wall time: 1238s; CPU: 53.0%; Read bytes: 1291264; Write bytes: 317569536; Read time: 16; Write time: 1560 11:13:17 INFO - Running post-run listener: _upload_blobber_files 11:13:17 INFO - Blob upload gear active. 11:13:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:13:17 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 11:13:17 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:13:17 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:13:17 INFO - (blobuploader) - INFO - Open directory for files ... 11:13:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:13:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:13:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:19 INFO - (blobuploader) - INFO - Done attempting. 11:13:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:13:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:13:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:20 INFO - (blobuploader) - INFO - Done attempting. 11:13:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:13:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:13:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:21 INFO - (blobuploader) - INFO - Done attempting. 11:13:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:13:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:13:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:23 INFO - (blobuploader) - INFO - Done attempting. 11:13:23 INFO - (blobuploader) - INFO - Iteration through files over. 11:13:23 INFO - Return code: 0 11:13:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:13:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:13:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c8527c85e9cb1f3c03e2a8b55e3b4fdff96fe20fca2de0a65725a4f6ef498d37e6f06a0e4cf9c93eb93303224e7e6d3ee500fcd9a6ea93d9af4d11b7fe8f55bd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8764f82e2db54e0388013d53a66d81e6d1c993cacbfdf0e51bf590146b6928019112ea2b9d86a78d52ab71cd9c67910b3ea2ea2ad611a3a4fb94f5795c9fe9b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c6a315d00e9701b2f4a9ebe5482187c84c3f542183b401fc75775b89f0fc872951d6eabbe19777e053c05d7a46aad4a51cf3c4d41a90295c1d2de07d82f33bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/496a4ec850938564932833fbc1ded16c3e01f4cd72e65b4485397f83226f7b33286ff69c5fe0fa4b85bd73c7000548774abd93deec3d82be57c3dde2ba579186"} 11:13:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:13:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:13:23 INFO - Contents: 11:13:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c8527c85e9cb1f3c03e2a8b55e3b4fdff96fe20fca2de0a65725a4f6ef498d37e6f06a0e4cf9c93eb93303224e7e6d3ee500fcd9a6ea93d9af4d11b7fe8f55bd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8764f82e2db54e0388013d53a66d81e6d1c993cacbfdf0e51bf590146b6928019112ea2b9d86a78d52ab71cd9c67910b3ea2ea2ad611a3a4fb94f5795c9fe9b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c6a315d00e9701b2f4a9ebe5482187c84c3f542183b401fc75775b89f0fc872951d6eabbe19777e053c05d7a46aad4a51cf3c4d41a90295c1d2de07d82f33bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/496a4ec850938564932833fbc1ded16c3e01f4cd72e65b4485397f83226f7b33286ff69c5fe0fa4b85bd73c7000548774abd93deec3d82be57c3dde2ba579186"} 11:13:23 INFO - Running post-run listener: copy_logs_to_upload_dir 11:13:23 INFO - Copying logs to upload dir... 11:13:23 INFO - mkdir: /builds/slave/test/build/upload/logs 11:13:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1342.761513 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-13 11:13:23.830329) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 11:13:23.834253) ========= 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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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/Cedar/sha512/c8527c85e9cb1f3c03e2a8b55e3b4fdff96fe20fca2de0a65725a4f6ef498d37e6f06a0e4cf9c93eb93303224e7e6d3ee500fcd9a6ea93d9af4d11b7fe8f55bd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8764f82e2db54e0388013d53a66d81e6d1c993cacbfdf0e51bf590146b6928019112ea2b9d86a78d52ab71cd9c67910b3ea2ea2ad611a3a4fb94f5795c9fe9b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c6a315d00e9701b2f4a9ebe5482187c84c3f542183b401fc75775b89f0fc872951d6eabbe19777e053c05d7a46aad4a51cf3c4d41a90295c1d2de07d82f33bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/496a4ec850938564932833fbc1ded16c3e01f4cd72e65b4485397f83226f7b33286ff69c5fe0fa4b85bd73c7000548774abd93deec3d82be57c3dde2ba579186"} build_url:https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011786 build_url: 'https://queue.taskcluster.net/v1/task/Bw8MRetuSX-rlbh2Y5OAtQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c8527c85e9cb1f3c03e2a8b55e3b4fdff96fe20fca2de0a65725a4f6ef498d37e6f06a0e4cf9c93eb93303224e7e6d3ee500fcd9a6ea93d9af4d11b7fe8f55bd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8764f82e2db54e0388013d53a66d81e6d1c993cacbfdf0e51bf590146b6928019112ea2b9d86a78d52ab71cd9c67910b3ea2ea2ad611a3a4fb94f5795c9fe9b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c6a315d00e9701b2f4a9ebe5482187c84c3f542183b401fc75775b89f0fc872951d6eabbe19777e053c05d7a46aad4a51cf3c4d41a90295c1d2de07d82f33bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/496a4ec850938564932833fbc1ded16c3e01f4cd72e65b4485397f83226f7b33286ff69c5fe0fa4b85bd73c7000548774abd93deec3d82be57c3dde2ba579186"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 11:13:23.867564) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 11:13:23.867895) ========= 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.RKhfXtoZ1N/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.Ku2kcix7w2/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005382 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 11:13:23.916371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 11:13:23.916654) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 11:13:23.916950) ========= ========= Total master_lag: 0.17 =========