builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0317 starttime: 1463773633.02 results: success (0) buildid: 20160520103438 builduid: 26e383726df4460b85eef6a0f8cd9851 revision: bb07b5227ff1683f14ae78cdd630164969335608 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.020797) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.021176) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.021450) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.093075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.093379) ========= 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.ACmWMORcLF/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.z9GDplOEva/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 --2016-05-20 12:47:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.86M=0.002s 2016-05-20 12:47:13 (6.86 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.572092 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.686271) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.686587) ========= 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.ACmWMORcLF/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.z9GDplOEva/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.019997 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:13.735623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 12:47:13.736018) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bb07b5227ff1683f14ae78cdd630164969335608 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bb07b5227ff1683f14ae78cdd630164969335608 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ACmWMORcLF/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.z9GDplOEva/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 2016-05-20 12:47:13,771 truncating revision to first 12 chars 2016-05-20 12:47:13,771 Setting DEBUG logging. 2016-05-20 12:47:13,771 attempt 1/10 2016-05-20 12:47:13,771 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bb07b5227ff1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 12:47:14,750 unpacking tar archive at: mozilla-beta-bb07b5227ff1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.228021 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 12:47:14.981763) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:14.982045) ========= script_repo_revision: bb07b5227ff1683f14ae78cdd630164969335608 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:14.982417) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:14.982682) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 12:47:14.996945) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2016-05-20 12:47:14.997233) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ACmWMORcLF/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.z9GDplOEva/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:47:15 INFO - MultiFileLogger online at 20160520 12:47:15 in /builds/slave/test 12:47:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:47:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:47:15 INFO - {'append_to_log': False, 12:47:15 INFO - 'base_work_dir': '/builds/slave/test', 12:47:15 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:47:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:47:15 INFO - 'branch': 'Mozilla-Beta', 12:47:15 INFO - 'buildbot_json_path': 'buildprops.json', 12:47:15 INFO - 'config_files': ('talos/mac_config.py',), 12:47:15 INFO - 'default_actions': ('clobber', 12:47:15 INFO - 'read-buildbot-config', 12:47:15 INFO - 'download-and-extract', 12:47:15 INFO - 'populate-webroot', 12:47:15 INFO - 'create-virtualenv', 12:47:15 INFO - 'install', 12:47:15 INFO - 'run-tests'), 12:47:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:47:15 INFO - 'download_minidump_stackwalk': True, 12:47:15 INFO - 'download_symbols': 'ondemand', 12:47:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:47:15 INFO - 'tooltool.py': '/tools/tooltool.py', 12:47:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:47:15 INFO - '/tools/misc-python/virtualenv.py')}, 12:47:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:47:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:47:15 INFO - 'installer_path': 'installer.exe', 12:47:15 INFO - 'log_level': 'info', 12:47:15 INFO - 'log_name': 'talos', 12:47:15 INFO - 'log_to_console': True, 12:47:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:47:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:47:15 INFO - 'opt_config_files': (), 12:47:15 INFO - 'pip_index': False, 12:47:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:15 INFO - 'cmd': ('bash', 12:47:15 INFO - '-c', 12:47:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:15 INFO - 'enabled': True, 12:47:15 INFO - 'halt_on_failure': False, 12:47:15 INFO - 'name': 'check_screen_resolution'},), 12:47:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:15 INFO - 'cmd': ('bash', 12:47:15 INFO - '-c', 12:47:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:15 INFO - 'enabled': True, 12:47:15 INFO - 'halt_on_failure': False, 12:47:15 INFO - 'name': 'check_screen_resolution'},), 12:47:15 INFO - 'run_cmd_checks_enabled': True, 12:47:15 INFO - 'sps_profile': False, 12:47:15 INFO - 'sps_profile_interval': 0, 12:47:15 INFO - 'suite': 'dromaeojs-e10s', 12:47:15 INFO - 'system_bits': '32', 12:47:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:47:15 INFO - 'title': 't-yosemite-r7-0317', 12:47:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:47:15 INFO - 'use_talos_json': True, 12:47:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:47:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:47:15 INFO - 'work_dir': 'build'} 12:47:15 INFO - ##### 12:47:15 INFO - ##### Running clobber step. 12:47:15 INFO - ##### 12:47:15 INFO - Running pre-action listener: _resource_record_pre_action 12:47:15 INFO - Running main action method: clobber 12:47:15 INFO - rmtree: /builds/slave/test/build 12:47:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:47:16 INFO - Running post-action listener: _resource_record_post_action 12:47:16 INFO - ##### 12:47:16 INFO - ##### Running read-buildbot-config step. 12:47:16 INFO - ##### 12:47:16 INFO - Running pre-action listener: _resource_record_pre_action 12:47:16 INFO - Running main action method: read_buildbot_config 12:47:16 INFO - Using buildbot properties: 12:47:16 INFO - { 12:47:16 INFO - "project": "", 12:47:16 INFO - "product": "firefox", 12:47:16 INFO - "script_repo_revision": "production", 12:47:16 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 12:47:16 INFO - "repository": "", 12:47:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 12:47:16 INFO - "buildid": "20160520103438", 12:47:16 INFO - "pgo_build": "False", 12:47:16 INFO - "basedir": "/builds/slave/test", 12:47:16 INFO - "buildnumber": 20, 12:47:16 INFO - "slavename": "t-yosemite-r7-0317", 12:47:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:47:16 INFO - "platform": "yosemite_r7", 12:47:16 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:47:16 INFO - "branch": "mozilla-beta", 12:47:16 INFO - "repo_path": "releases/mozilla-beta", 12:47:16 INFO - "revision": "bb07b5227ff1683f14ae78cdd630164969335608", 12:47:16 INFO - "stage_platform": "macosx64", 12:47:16 INFO - "builduid": "26e383726df4460b85eef6a0f8cd9851", 12:47:16 INFO - "slavebuilddir": "test" 12:47:16 INFO - } 12:47:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 12:47:16 INFO - Running post-action listener: _resource_record_post_action 12:47:16 INFO - ##### 12:47:16 INFO - ##### Running download-and-extract step. 12:47:16 INFO - ##### 12:47:16 INFO - Running pre-action listener: _resource_record_pre_action 12:47:16 INFO - Running main action method: download_and_extract 12:47:16 INFO - mkdir: /builds/slave/test/build/tests 12:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:16 INFO - https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:47:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 12:47:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 12:47:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 12:47:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 12:47:47 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json: timed out 12:47:47 INFO - retry: attempt #1 caught exception: timed out 12:47:47 INFO - retry: Failed, sleeping 30 seconds before retrying 12:48:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #2 12:48:20 INFO - Downloaded 1241 bytes. 12:48:20 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 12:48:20 INFO - Using the following test package requirements: 12:48:20 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:48:20 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:48:20 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 12:48:20 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:48:20 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 12:48:20 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:48:20 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:48:20 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 12:48:20 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:48:20 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:48:20 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 12:48:20 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:48:20 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 12:48:20 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:48:20 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 12:48:20 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:48:20 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:48:20 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 12:48:20 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 12:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:20 INFO - https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:48:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:48:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:48:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 12:48:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 12:48:21 INFO - Downloaded 17747777 bytes. 12:48:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:48:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:48:24 INFO - Return code: 0 12:48:24 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:24 INFO - https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:48:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:48:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:48:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 12:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 12:48:38 INFO - Downloaded 17747777 bytes. 12:48:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:48:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:48:41 INFO - Return code: 0 12:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:41 INFO - https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:48:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 12:48:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 12:48:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 12:48:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 12:48:42 INFO - Downloaded 10845194 bytes. 12:48:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:48:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:48:42 INFO - Return code: 0 12:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:42 INFO - https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 12:48:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:48:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:48:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 12:48:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 12:48:49 INFO - Downloaded 88422312 bytes. 12:48:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:48:49 INFO - mkdir: /builds/slave/test/properties 12:48:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:48:49 INFO - Writing to file /builds/slave/test/properties/build_url 12:48:49 INFO - Contents: 12:48:49 INFO - build_url:https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:48:50 INFO - Running post-action listener: _resource_record_post_action 12:48:50 INFO - Running post-action listener: set_extra_try_arguments 12:48:50 INFO - ##### 12:48:50 INFO - ##### Running populate-webroot step. 12:48:50 INFO - ##### 12:48:50 INFO - Running pre-action listener: _resource_record_pre_action 12:48:50 INFO - Running main action method: populate_webroot 12:48:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:48:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:48:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:48:50 INFO - '--tppagecycles', 12:48:50 INFO - '7'], 12:48:50 INFO - 'tests': ['tsvgm']}}, 12:48:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:48:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:48:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 12:48:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:48:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:48:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:48:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'tests': ['damp', 'tps']}, 12:48:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['damp', 'tps']}, 12:48:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:48:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:48:50 INFO - 'other': {'tests': ['a11yr', 12:48:50 INFO - 'ts_paint', 12:48:50 INFO - 'tpaint', 12:48:50 INFO - 'sessionrestore', 12:48:50 INFO - 'sessionrestore_no_auto_restore', 12:48:50 INFO - 'tabpaint']}, 12:48:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['a11yr', 12:48:50 INFO - 'ts_paint', 12:48:50 INFO - 'tpaint', 12:48:50 INFO - 'sessionrestore', 12:48:50 INFO - 'sessionrestore_no_auto_restore', 12:48:50 INFO - 'tabpaint']}, 12:48:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['a11yr', 12:48:50 INFO - 'ts_paint', 12:48:50 INFO - 'tpaint', 12:48:50 INFO - 'sessionrestore', 12:48:50 INFO - 'sessionrestore_no_auto_restore', 12:48:50 INFO - 'tabpaint']}, 12:48:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['a11yr', 12:48:50 INFO - 'ts_paint', 12:48:50 INFO - 'tpaint', 12:48:50 INFO - 'sessionrestore', 12:48:50 INFO - 'sessionrestore_no_auto_restore', 12:48:50 INFO - 'tabpaint']}, 12:48:50 INFO - 'other_l64': {'tests': ['a11yr', 12:48:50 INFO - 'ts_paint', 12:48:50 INFO - 'tpaint', 12:48:50 INFO - 'sessionrestore', 12:48:50 INFO - 'sessionrestore_no_auto_restore', 12:48:50 INFO - 'tabpaint']}, 12:48:50 INFO - 'other_nol64': {'tests': ['a11yr', 12:48:50 INFO - 'ts_paint', 12:48:50 INFO - 'tpaint', 12:48:50 INFO - 'sessionrestore', 12:48:50 INFO - 'sessionrestore_no_auto_restore', 12:48:50 INFO - 'tabpaint']}, 12:48:50 INFO - 'svgr': {'tests': ['tsvgx', 12:48:50 INFO - 'tsvgr_opacity', 12:48:50 INFO - 'tart', 12:48:50 INFO - 'tscrollx', 12:48:50 INFO - 'cart']}, 12:48:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['tsvgx', 12:48:50 INFO - 'tsvgr_opacity', 12:48:50 INFO - 'tart', 12:48:50 INFO - 'tscrollx', 12:48:50 INFO - 'cart']}, 12:48:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'tests': ['tp5o']}, 12:48:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'talos_options': ['--e10s'], 12:48:50 INFO - 'tests': ['tp5o']}, 12:48:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'talos_options': ['--xperf_path', 12:48:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:48:50 INFO - 'tests': ['tp5n']}, 12:48:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:48:50 INFO - 'talos_options': ['--e10s', 12:48:50 INFO - '--xperf_path', 12:48:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:48:50 INFO - 'tests': ['tp5n']}}, 12:48:50 INFO - 'talos.zip': {'path': '', 12:48:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:48:50 INFO - Running post-action listener: _resource_record_post_action 12:48:50 INFO - ##### 12:48:50 INFO - ##### Running create-virtualenv step. 12:48:50 INFO - ##### 12:48:50 INFO - Running pre-action listener: _resource_record_pre_action 12:48:50 INFO - Running main action method: create_virtualenv 12:48:50 INFO - Creating virtualenv /builds/slave/test/build/venv 12:48:50 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 12:48:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:48:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:48:50 INFO - Using real prefix '/tools/python27' 12:48:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:48:51 INFO - Installing distribute.............................................................................................................................................................................................done. 12:48:54 INFO - Installing pip.................done. 12:48:54 INFO - Return code: 0 12:48:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:54 INFO - 'HOME': '/Users/cltbld', 12:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:54 INFO - 'LOGNAME': 'cltbld', 12:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:54 INFO - 'MOZ_NO_REMOTE': '1', 12:48:54 INFO - 'NO_EM_RESTART': '1', 12:48:54 INFO - 'PAGER': '/bin/cat', 12:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:54 INFO - 'PWD': '/builds/slave/test', 12:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:54 INFO - 'SHELL': '/bin/bash', 12:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:48:54 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:54 INFO - 'USER': 'cltbld', 12:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:54 INFO - 'XPC_FLAGS': '0x0', 12:48:54 INFO - 'XPC_SERVICE_NAME': '0', 12:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:54 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 12:48:54 INFO - Cleaning up... 12:48:54 INFO - Return code: 0 12:48:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 12:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:54 INFO - 'HOME': '/Users/cltbld', 12:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:54 INFO - 'LOGNAME': 'cltbld', 12:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:54 INFO - 'MOZ_NO_REMOTE': '1', 12:48:54 INFO - 'NO_EM_RESTART': '1', 12:48:54 INFO - 'PAGER': '/bin/cat', 12:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:54 INFO - 'PWD': '/builds/slave/test', 12:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:54 INFO - 'SHELL': '/bin/bash', 12:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:48:54 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:54 INFO - 'USER': 'cltbld', 12:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:54 INFO - 'XPC_FLAGS': '0x0', 12:48:54 INFO - 'XPC_SERVICE_NAME': '0', 12:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:54 INFO - Downloading/unpacking psutil>=0.7.1 12:48:54 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 12:48:54 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 12:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:54 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 12:48:54 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 12:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:48:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:48:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:48:58 INFO - Installing collected packages: psutil 12:48:58 INFO - Running setup.py install for psutil 12:48:58 INFO - building 'psutil._psutil_osx' extension 12:48:58 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 12:48:58 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 12:48:58 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 12:48:58 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 12:48:58 INFO - building 'psutil._psutil_posix' extension 12:48:58 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 12:48:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:48:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:48:58 INFO - ^ 12:48:58 INFO - 1 warning generated. 12:48:58 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 12:48:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:48:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:48:59 INFO - Successfully installed psutil 12:48:59 INFO - Cleaning up... 12:48:59 INFO - Return code: 0 12:48:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:48:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:48:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:48:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:59 INFO - 'HOME': '/Users/cltbld', 12:48:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:59 INFO - 'LOGNAME': 'cltbld', 12:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:59 INFO - 'MOZ_NO_REMOTE': '1', 12:48:59 INFO - 'NO_EM_RESTART': '1', 12:48:59 INFO - 'PAGER': '/bin/cat', 12:48:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:59 INFO - 'PWD': '/builds/slave/test', 12:48:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:59 INFO - 'SHELL': '/bin/bash', 12:48:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:48:59 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:48:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:59 INFO - 'USER': 'cltbld', 12:48:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:59 INFO - 'XPC_FLAGS': '0x0', 12:48:59 INFO - 'XPC_SERVICE_NAME': '0', 12:48:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:49:01 INFO - Installing collected packages: mozsystemmonitor 12:49:01 INFO - Running setup.py install for mozsystemmonitor 12:49:02 INFO - Successfully installed mozsystemmonitor 12:49:02 INFO - Cleaning up... 12:49:02 INFO - Return code: 0 12:49:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:02 INFO - 'HOME': '/Users/cltbld', 12:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:02 INFO - 'LOGNAME': 'cltbld', 12:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:02 INFO - 'MOZ_NO_REMOTE': '1', 12:49:02 INFO - 'NO_EM_RESTART': '1', 12:49:02 INFO - 'PAGER': '/bin/cat', 12:49:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:02 INFO - 'PWD': '/builds/slave/test', 12:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:02 INFO - 'SHELL': '/bin/bash', 12:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:49:02 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:02 INFO - 'USER': 'cltbld', 12:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:02 INFO - 'XPC_FLAGS': '0x0', 12:49:02 INFO - 'XPC_SERVICE_NAME': '0', 12:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:02 INFO - Downloading/unpacking blobuploader==1.2.4 12:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:04 INFO - Downloading blobuploader-1.2.4.tar.gz 12:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:49:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:49:04 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 12:49:04 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 12:49:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:04 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 12:49:04 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 12:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:49:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:05 INFO - Downloading docopt-0.6.1.tar.gz 12:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:49:06 INFO - Installing collected packages: blobuploader, requests, docopt 12:49:06 INFO - Running setup.py install for blobuploader 12:49:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:49:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:49:06 INFO - Running setup.py install for requests 12:49:06 INFO - Running setup.py install for docopt 12:49:06 INFO - Successfully installed blobuploader requests docopt 12:49:06 INFO - Cleaning up... 12:49:06 INFO - Return code: 0 12:49:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:49:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:49:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:06 INFO - 'HOME': '/Users/cltbld', 12:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:06 INFO - 'LOGNAME': 'cltbld', 12:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:06 INFO - 'MOZ_NO_REMOTE': '1', 12:49:06 INFO - 'NO_EM_RESTART': '1', 12:49:06 INFO - 'PAGER': '/bin/cat', 12:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:06 INFO - 'PWD': '/builds/slave/test', 12:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:06 INFO - 'SHELL': '/bin/bash', 12:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:49:06 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:06 INFO - 'USER': 'cltbld', 12:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:06 INFO - 'XPC_FLAGS': '0x0', 12:49:06 INFO - 'XPC_SERVICE_NAME': '0', 12:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-JKqv90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-D5ab7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-BKWoXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-czNCzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ZHzDmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-7j4hLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-xCeVCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:49:07 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-X08kzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-XkfQXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-hAgEkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-VOIeqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-hGO9EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-WuaXsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-9ZIUAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ksqT1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-DzS9X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:49:08 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-zU9pwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:49:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:49:08 INFO - Running setup.py install for manifestparser 12:49:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:49:09 INFO - Running setup.py install for mozcrash 12:49:09 INFO - Running setup.py install for mozdebug 12:49:09 INFO - Running setup.py install for mozdevice 12:49:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:49:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:49:09 INFO - Running setup.py install for mozfile 12:49:09 INFO - Running setup.py install for mozhttpd 12:49:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:49:09 INFO - Running setup.py install for mozinfo 12:49:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Running setup.py install for mozInstall 12:49:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Running setup.py install for mozleak 12:49:10 INFO - Running setup.py install for mozlog 12:49:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Running setup.py install for moznetwork 12:49:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Running setup.py install for mozprocess 12:49:10 INFO - Running setup.py install for mozprofile 12:49:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:49:10 INFO - Running setup.py install for mozrunner 12:49:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:49:11 INFO - Running setup.py install for mozscreenshot 12:49:11 INFO - Running setup.py install for moztest 12:49:11 INFO - Running setup.py install for mozversion 12:49:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:49:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:49:11 INFO - Cleaning up... 12:49:11 INFO - Return code: 0 12:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:49:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:49:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:49:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:49:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:11 INFO - 'HOME': '/Users/cltbld', 12:49:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:11 INFO - 'LOGNAME': 'cltbld', 12:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:11 INFO - 'MOZ_NO_REMOTE': '1', 12:49:11 INFO - 'NO_EM_RESTART': '1', 12:49:11 INFO - 'PAGER': '/bin/cat', 12:49:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:11 INFO - 'PWD': '/builds/slave/test', 12:49:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:11 INFO - 'SHELL': '/bin/bash', 12:49:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:49:11 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:49:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:11 INFO - 'USER': 'cltbld', 12:49:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:11 INFO - 'XPC_FLAGS': '0x0', 12:49:11 INFO - 'XPC_SERVICE_NAME': '0', 12:49:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:49:11 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-nyq8zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:49:11 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)) 12:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:49:11 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-PVe49F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-k5HyN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:49:12 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-VyK4Xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-lsSY4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:49:12 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-76eHK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:49:12 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-P23Lhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-KjgX4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-mfUBlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:49:12 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-4qOYbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-IghLyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:49:12 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)) 12:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:49:12 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-CbZ1TG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 12:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-H5n3PH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:49:13 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)) 12:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Q7aWuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 12:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-XUBZOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:13 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)) 12:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-mLi7F6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 12:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-joLhZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:49:13 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)) 12:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:49:13 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)) 12:49:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:49:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:49:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:15 INFO - Downloading blessings-1.6.tar.gz 12:49:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:49:16 INFO - Installing collected packages: blessings 12:49:16 INFO - Running setup.py install for blessings 12:49:16 INFO - Successfully installed blessings 12:49:16 INFO - Cleaning up... 12:49:16 INFO - Return code: 0 12:49:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:49:16 INFO - Reading from file tmpfile_stdout 12:49:16 INFO - Current package versions: 12:49:16 INFO - blessings == 1.6 12:49:16 INFO - blobuploader == 1.2.4 12:49:16 INFO - docopt == 0.6.1 12:49:16 INFO - manifestparser == 1.1 12:49:16 INFO - mozInstall == 1.12 12:49:16 INFO - mozcrash == 0.17 12:49:16 INFO - mozdebug == 0.1 12:49:16 INFO - mozdevice == 0.48 12:49:16 INFO - mozfile == 1.2 12:49:16 INFO - mozhttpd == 0.7 12:49:16 INFO - mozinfo == 0.9 12:49:16 INFO - mozleak == 0.1 12:49:16 INFO - mozlog == 3.1 12:49:16 INFO - moznetwork == 0.27 12:49:16 INFO - mozprocess == 0.22 12:49:16 INFO - mozprofile == 0.28 12:49:16 INFO - mozrunner == 6.11 12:49:16 INFO - mozscreenshot == 0.1 12:49:16 INFO - mozsystemmonitor == 0.0 12:49:16 INFO - moztest == 0.7 12:49:16 INFO - mozversion == 1.4 12:49:16 INFO - psutil == 3.1.1 12:49:16 INFO - requests == 1.2.3 12:49:16 INFO - wsgiref == 0.1.2 12:49:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:49:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:49:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:16 INFO - 'HOME': '/Users/cltbld', 12:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:16 INFO - 'LOGNAME': 'cltbld', 12:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:16 INFO - 'MOZ_NO_REMOTE': '1', 12:49:16 INFO - 'NO_EM_RESTART': '1', 12:49:16 INFO - 'PAGER': '/bin/cat', 12:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:16 INFO - 'PWD': '/builds/slave/test', 12:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:16 INFO - 'SHELL': '/bin/bash', 12:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:49:16 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:16 INFO - 'USER': 'cltbld', 12:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:16 INFO - 'XPC_FLAGS': '0x0', 12:49:16 INFO - 'XPC_SERVICE_NAME': '0', 12:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 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)) 12:49:16 INFO - Cleaning up... 12:49:16 INFO - Return code: 0 12:49:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:16 INFO - 'HOME': '/Users/cltbld', 12:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:16 INFO - 'LOGNAME': 'cltbld', 12:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:16 INFO - 'MOZ_NO_REMOTE': '1', 12:49:16 INFO - 'NO_EM_RESTART': '1', 12:49:16 INFO - 'PAGER': '/bin/cat', 12:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:16 INFO - 'PWD': '/builds/slave/test', 12:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:16 INFO - 'SHELL': '/bin/bash', 12:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:49:16 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:16 INFO - 'USER': 'cltbld', 12:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:16 INFO - 'XPC_FLAGS': '0x0', 12:49:16 INFO - 'XPC_SERVICE_NAME': '0', 12:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:17 INFO - Downloading/unpacking jsonschema 12:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:49:19 INFO - Downloading/unpacking functools32 (from jsonschema) 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:20 INFO - Downloading functools32-3.2.3-2.tar.gz 12:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:49:20 INFO - warning: no files found matching '*.txt' 12:49:20 INFO - no previously-included directories found matching 'build' 12:49:20 INFO - no previously-included directories found matching 'dist' 12:49:20 INFO - no previously-included directories found matching '.git*' 12:49:20 INFO - Installing collected packages: jsonschema, functools32 12:49:20 INFO - Running setup.py install for functools32 12:49:20 INFO - warning: no files found matching '*.txt' 12:49:20 INFO - no previously-included directories found matching 'build' 12:49:20 INFO - no previously-included directories found matching 'dist' 12:49:20 INFO - no previously-included directories found matching '.git*' 12:49:20 INFO - Successfully installed jsonschema functools32 12:49:20 INFO - Cleaning up... 12:49:20 INFO - Return code: 0 12:49:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9a14c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb71949d6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, '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.ACmWMORcLF/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.z9GDplOEva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 12:49:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:21 INFO - 'HOME': '/Users/cltbld', 12:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:21 INFO - 'LOGNAME': 'cltbld', 12:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:21 INFO - 'MOZ_NO_REMOTE': '1', 12:49:21 INFO - 'NO_EM_RESTART': '1', 12:49:21 INFO - 'PAGER': '/bin/cat', 12:49:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:21 INFO - 'PWD': '/builds/slave/test', 12:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:21 INFO - 'SHELL': '/bin/bash', 12:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:49:21 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:21 INFO - 'USER': 'cltbld', 12:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:21 INFO - 'XPC_FLAGS': '0x0', 12:49:21 INFO - 'XPC_SERVICE_NAME': '0', 12:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:21 INFO - Downloading/unpacking flake8 12:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:49:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:49:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:49:24 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 12:49:24 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 12:49:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:24 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 12:49:24 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 12:49:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:49:24 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 12:49:24 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 12:49:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:24 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 12:49:24 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 12:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:49:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:49:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:49:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:49:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:49:25 INFO - no previously-included directories found matching 'docs/_build' 12:49:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:49:25 INFO - Running setup.py install for pep8 12:49:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:49:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:49:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:49:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:49:25 INFO - no previously-included directories found matching 'docs/_build' 12:49:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:49:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:49:25 INFO - Cleaning up... 12:49:25 INFO - Return code: 0 12:49:25 INFO - Running post-action listener: _resource_record_post_action 12:49:25 INFO - Running post-action listener: _start_resource_monitoring 12:49:25 INFO - Starting resource monitoring. 12:49:25 INFO - ##### 12:49:25 INFO - ##### Running install step. 12:49:25 INFO - ##### 12:49:25 INFO - Running pre-action listener: _resource_record_pre_action 12:49:25 INFO - Running main action method: install 12:49:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:49:25 INFO - Reading from file tmpfile_stdout 12:49:25 INFO - Detecting whether we're running mozinstall >=1.0... 12:49:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:49:25 INFO - Reading from file tmpfile_stdout 12:49:25 INFO - Output received: 12:49:25 INFO - Usage: mozinstall [options] installer 12:49:25 INFO - Options: 12:49:25 INFO - -h, --help show this help message and exit 12:49:25 INFO - -d DEST, --destination=DEST 12:49:25 INFO - Directory to install application into. [default: 12:49:25 INFO - "/builds/slave/test"] 12:49:25 INFO - --app=APP Application being installed. [default: firefox] 12:49:25 INFO - mkdir: /builds/slave/test/build/application 12:49:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:49:48 INFO - Reading from file tmpfile_stdout 12:49:48 INFO - Output received: 12:49:48 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:49:48 INFO - Running post-action listener: _resource_record_post_action 12:49:48 INFO - ##### 12:49:48 INFO - ##### Running run-tests step. 12:49:48 INFO - ##### 12:49:48 INFO - Running pre-action listener: _resource_record_pre_action 12:49:48 INFO - Running main action method: run_tests 12:49:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:49:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:49:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:49:48 INFO - 2016-05-20 12:49:48.573 screenresolution[5953:94208] starting screenresolution argv=screenresolution get 12:49:48 INFO - 2016-05-20 12:49:48.576 screenresolution[5953:94208] Display 0: 1600x1200x32@60 12:49:48 INFO - 2016-05-20 12:49:48.582 screenresolution[5954:94210] starting screenresolution argv=screenresolution list 12:49:48 INFO - Available Modes on Display 0 12:49:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:49:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:49:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:49:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:49:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:49:48 INFO - Intel Iris: 12:49:48 INFO - Chipset Model: Intel Iris 12:49:48 INFO - Type: GPU 12:49:48 INFO - Bus: Built-In 12:49:48 INFO - VRAM (Dynamic, Max): 1536 MB 12:49:48 INFO - Vendor: Intel (0x8086) 12:49:48 INFO - Device ID: 0x0a2e 12:49:48 INFO - Revision ID: 0x0009 12:49:48 INFO - Displays: 12:49:48 INFO - Display: 12:49:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:49:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:49:48 INFO - Display Serial Number: 1600x1200 60 12:49:48 INFO - Main Display: Yes 12:49:48 INFO - Mirror: Off 12:49:48 INFO - Online: Yes 12:49:48 INFO - Rotation: Supported 12:49:48 INFO - Return code: 0 12:49:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:49:48 INFO - Python 2.7.3 12:49:48 INFO - Return code: 0 12:49:48 INFO - grabbing minidump binary from tooltool 12:49:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:48 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f83ed30>, '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 0x10f6a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f835b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:49:48 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 12:49:48 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 12:49:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:49:48 INFO - Return code: 0 12:49:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:49:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:49:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:49:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:49:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:49:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:49:50 INFO - Return code: 0 12:49:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 12:49:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0317 --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 12:49:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ACmWMORcLF/Render', 12:49:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:50 INFO - 'HOME': '/Users/cltbld', 12:49:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:50 INFO - 'LOGNAME': 'cltbld', 12:49:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:49:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:50 INFO - 'MOZ_NO_REMOTE': '1', 12:49:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:49:50 INFO - 'NO_EM_RESTART': '1', 12:49:50 INFO - 'PAGER': '/bin/cat', 12:49:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:50 INFO - 'PWD': '/builds/slave/test', 12:49:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:49:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:50 INFO - 'SHELL': '/bin/bash', 12:49:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9GDplOEva/Listeners', 12:49:50 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 12:49:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:50 INFO - 'USER': 'cltbld', 12:49:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:50 INFO - 'XPC_FLAGS': '0x0', 12:49:50 INFO - 'XPC_SERVICE_NAME': '0', 12:49:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 12:49:50 INFO - mozversion application_buildid: 20160520103438 12:49:50 INFO - mozversion application_changeset: bb07b5227ff1683f14ae78cdd630164969335608 12:49:50 INFO - mozversion application_display_name: Firefox 12:49:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:49:50 INFO - mozversion application_name: Firefox 12:49:50 INFO - mozversion application_remotingname: firefox 12:49:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:49:50 INFO - mozversion application_vendor: Mozilla 12:49:50 INFO - mozversion application_version: 47.0 12:49:50 INFO - mozversion platform_buildid: 20160520103438 12:49:50 INFO - mozversion platform_changeset: bb07b5227ff1683f14ae78cdd630164969335608 12:49:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:49:50 INFO - mozversion platform_version: 47.0 12:49:50 INFO - using testdate: 1463773790 12:49:50 INFO - actual date: 1463773790 12:49:50 INFO - starting webserver on 'localhost:50496' 12:49:50 INFO - SUITE-START | Running 2 tests 12:49:50 INFO - TEST-START | dromaeo_css 12:49:50 INFO - operating with platform_type : mac_ 12:49:50 INFO - Initialising browser for dromaeo_css test... 12:49:50 INFO - TEST-INFO | started process 5972 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpP5Lv09/profile http://localhost:50496/getInfo.html) 12:49:53 INFO - PROCESS | 5972 | __metrics Screen width/height:1600/1200 12:49:53 INFO - PROCESS | 5972 | colorDepth:24 12:49:53 INFO - PROCESS | 5972 | Browser inner width/height: 1024/689 12:49:53 INFO - PROCESS | 5972 | __metrics 12:49:53 ERROR - PROCESS | 5972 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 12:49:53 INFO - TEST-INFO | 5972: exit 0 12:49:53 INFO - Browser initialized. 12:49:53 INFO - Running cycle 1/1 for dromaeo_css test... 12:49:53 INFO - TEST-INFO | started process 5976 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpP5Lv09/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:50:05 INFO - PROCESS | 5976 | RSS: Main: 168083456 12:50:05 INFO - PROCESS | 5976 | 12:50:55 INFO - PROCESS | 5976 | 1463773855365 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:53:05 INFO - PROCESS | 5976 | Cycle 1(1): loaded http://localhost:50496/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50496/tests/dromaeo/cssquery-ext.html) 12:53:06 INFO - PROCESS | 5976 | RSS: Main: 178565120 12:53:06 INFO - PROCESS | 5976 | 12:56:08 INFO - PROCESS | 5976 | Cycle 1(1): loaded http://localhost:50496/tests/dromaeo/cssquery-ext.html (next: http://localhost:50496/tests/dromaeo/cssquery-jquery.html) 12:56:09 INFO - PROCESS | 5976 | RSS: Main: 189845504 12:56:09 INFO - PROCESS | 5976 | 12:59:10 INFO - PROCESS | 5976 | Cycle 1(1): loaded http://localhost:50496/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50496/tests/dromaeo/cssquery-mootools.html) 12:59:11 INFO - PROCESS | 5976 | RSS: Main: 192430080 12:59:11 INFO - PROCESS | 5976 | 13:02:12 INFO - PROCESS | 5976 | Cycle 1(1): loaded http://localhost:50496/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50496/tests/dromaeo/cssquery-prototype.html) 13:02:13 INFO - PROCESS | 5976 | RSS: Main: 195096576 13:02:13 INFO - PROCESS | 5976 | 13:05:16 INFO - PROCESS | 5976 | Cycle 1(1): loaded http://localhost:50496/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50496/tests/dromaeo/cssquery-yui.html) 13:05:16 INFO - PROCESS | 5976 | RSS: Main: 195051520 13:05:16 INFO - PROCESS | 5976 | 13:08:18 INFO - PROCESS | 5976 | Cycle 1(1): loaded http://localhost:50496/tests/dromaeo/cssquery-yui.html (next: http://localhost:50496/tests/dromaeo/cssquery-dojo.html) 13:08:19 INFO - PROCESS | 5976 | RSS: Main: 200089600 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | __start_tp_report 13:08:19 INFO - PROCESS | 5976 | _x_x_mozilla_page_load 13:08:19 INFO - PROCESS | 5976 | _x_x_mozilla_page_load_details 13:08:19 INFO - PROCESS | 5976 | |i|pagename|runs| 13:08:19 INFO - PROCESS | 5976 | |0;dojo.html;3159.4470967580673;3608.512850765146;3632.54593175853;3633.146210640499;3672.2288319452914;5842.44496772807;5860.413958604137;5869.559783016275;5892.469677729004;5919.496842768364;5756.136579513065;5802.071668533035;5808.767649294024;5859.824205273838;5860.120981852724;5252.868678283045;5280.207968804691;5333.866653333659;5361.51746342829;5409.134538473845;4313.849015284466;4981.70475676325;5017.297578339036;5351.464853514656;5368.30212072432;5238.895222095556;5293.444188360225;5310.522053015228;5411.188321751749;5421.783128674848;5378;5379.596530260246;5383.2732581101445;5384.461553844623;5393.649412788195;4987.351644286219;5247.344081989751;5269.314989051399;5315.707636080014;5366.436524164946;4890.755462226874;5590.049691552405;5631.6902570359025;5652.406497317805;5691.715414229272;6202.41077097675;6206.6586337751405;6218.098375735493;6228.439440450381;6228.750849965996;6121.117656725397;6149.200603921507;6187.535934804908;6189.96905015472;6241.282252540925;5983.551233657493;5994.130851026664;5997.22036135304;6010.759569617167;6019.759209631609;6286.711543256046;6308.526860485482;6311.715972781236;6312;6318.778842740574;6087.147799308101;6102.69486525672;6134.957077260912;6143.603994442171;6178;5262.60530460217;5417.458254174551;6085.695715214221;6117.143599895929;6202.100695399142;6083.6045657032155;6158.3841615838055;6160.661163636043;6160.691965401712;6176.938230617726;6131.315499379051;6142.069075791497;6178.567500275028;6196.318404975539;6203.627782333075;4941.135301322254;4956.504349565087;4961.305417241589;4969.527894849984;4976.5023497649945;4295.334223195409;4415.4259946206785;4497.033137875373;4502.887427814266;4565.178267911808;5887.381824908323;5969.2240008799445;5993.910091348634;6025.427584379477;6031.9396806032255;6200.193974783249;6217.098520714561;6234.9712297471315;6240.375962403723;6268.686565671698;5854.648721076749;5855.648661080349;6018.488428483541;6060.33336333003;6086.543509236736;5425.972870135703;5428.837134885881;5438.993786149575;5459.972700136474;5493.697846618395;6378.553501254868;6394.168758061515;6395.328490508523;6437.0988061671405;6528.967355163289;6156.538259630456;6156.538259630635;6181.289151747606;6218.160548325918;6238.500919926304;6413.743450261937;6428.164338636135;6434.163558737334;6446.645434501147;6459.709313081016;6209.285932117864;6209.379062093935;6212.409821066992;6215.409536094064;6220.7511699531515;5867.266591676041;6152;6205.286392064971;6219.346968568235;6220.595661282002;6161.507079433724;6169.660668663267;6204.937950620617;6205.751769929152;6228.4705800007505;5976.342602313655;6075.878482430238;6186.041163619646;6241.750329986749;6254.562180647493;5294.861604754996;5385.434529374516;5979.22270104901;6112.99135642614;6170.598911070766;4769.73766442849;5114.744262786771;6177.505799535937;6186.659733714689;6236.968815155895;4659.8136074558;6102.328743838263;6189.04069869171;6212.4408803207925;6253.405926436982 13:08:19 INFO - PROCESS | 5976 | |1;ext.html;458.5895623417042;538.2491424462875;580.8228490310455;592.6710675575057;662.9834254143649;31380.372392552134;40015.39976900349;40015.79992100043;40288.99277518056;40299.597004029914;41200.969975750624;41224.58775412245;41823.16353672925;41890.16219675605;41950.37074443886;9507.19188868947;9627.422354658744;9651.565679544437;9679.322447428684;9750.171235444961;9161.267098632126;9263.166315031645;9269.814603707922;9335.393199442015;9398.966113727485;8900.910990890074;9219.95390023049;9259.305552083588;9390.248780097576;9419.293552983554;9007.45955242681;9163.725088247364;9217.032211617785;9365.81268374632;9414.199793017602;9790.06994335537;9885.703428897144;9908.10827025564;9981.401115933068;9982.900170998342;40118.39822402666;40241.195176096604;40567.39148912769;40828.59171408307;40923.7953810229;3050.4661684205175;3297.3240485700376;3376.64545222753;3458.239187378773;3551.8756843510355;27899;28003.439931201465;28069.157925262276;28225.71774282272;28790.856045719636;2802.1173330400857;2832.546792513188;2846.1603826871124;2860.4708128996203;2920.415916816624;444261;445118;447458;448738;451146;47056.5294347059;65205;65403.67298163479;65639.67180164068;65848.67075664687;46580.76709616498;46587;46760;46799.5320046802;46806.53193468022;17516.38692645751;17526;17569;17801.7329740054;17814;13981.930090349482;14037.438502459992;14246.572602821725;14301.141931484144;14355.64110897215;15301.005434646713;15424.228788560527;15430.5370838875;15488.845111548966;15550.922245388927;5568.44315568448;6471;6512.772052978123;6558.311377305409;6558.639274839929;993.7714325705128;1039.7088815131776;1057.962971296001;1182.467889449751;1195.3903509210365;26134.34664133412;30224.546631800542;30676.76045143819;30821.537676934862;30859.919902803293;1013.2856336282903;1122.6968718446137;1149.7642983188425;1188.916775825684;1230.4094034863315;11820.70448238801;12483.687907802378;13955.88352931763;14780.334884930204;16863.241154148094;496957;498687;499315;502238;503156.48421757657;52960.735196323774;62613.30400220002;62711;62745;63285;1530.4490383461946;1557.758547425151;1559.9844001560066;1564.4837203722516;1695.6778212139654;28656.713432865403;29970.550441743828;30573.388532228786;31373.843130785113;31814;53554;53603.73198133984;53717;54071.18893216526;54434.72782636061;507107;507530;508020;509534;515353;33533.664663353054;34650.82674586611;34719;34734.13164670904;35123.59505619747;45811.85470363268;46350.53649463462;46565.06869862516;47058.76470617625;47580.524194759;31941.041768746974;37177.44233836549;37710.434343485416;38406.6159338403;38742.80628596839;37637.81181094077;37844.243115138095;37880.810595946845;38090.61909380871;38336;32418;32947.68209271602;36012.81993590015;36555.63444365629;37805.487780488475;25675.871620642523;32374;33562;33989.150271243416;34261.65738342684;25127.623085654097;25263.242102736946;25304.493910122063;25527.489450211262;25560.744392555836 13:08:19 INFO - PROCESS | 5976 | |2;jquery.html;338.41961036821846;383.80425982748807;391.3972482377139;392.5387669488351;393.6850519584332;20500.237375377914;22181.667274990887;22542.774572254293;23153.884230578824;23270.88364558179;22147.667784983238;22280.888595557;22406.663900041513;22516.77483225167;22534.323970280875;5677.716114194273;5890.440408161217;5932.080527518242;6202.565820392574;6216.720247588858;4086.5913408659194;5802.361740208574;5988.670623115727;6074.301455332649;6080.939190608082;5124.256982737501;6055.424734650202;6064.848378790543;6071.908921366183;6078.088286757;5632.5212356949505;5779.624324418899;5804.564657650672;5914.674692891889;5926.792562260322;4925.21196608541;5363.410024897297;5539.778408863681;5675.233843431125;5692.7153642318135;13399.12905661129;14255.215963122024;14408.783868241984;21639.783602163934;25450.745492545022;2169.7504786949544;2176.2056849250066;2237.2505210754416;2389.008561446997;2605.1142611511987;10254.436006019667;10510.78978420435;10518.316309439939;11828.645140645709;15198.240087995666;1804.5127815489875;1848.2699333763223;1863.5806943437753;1866.0669665167416;1874.8218919202654;106944.55548448999;109663.35504967431;111429;115431.42284288525;116956.49130526098;9435;9533.237341012702;11458.427078646035;11738.354390508604;11843.703907402383;14522.128672279485;14559.708805823824;14566.907481938897;14632.268386580628;14688.853111468748;9151.496667683341;9160.450372977642;9235.261179105655;9293;9375.812483750287;9513.619455221713;9520.238380929648;9582.952085239529;9587;9678.95160524193;3886.689064874803;4645.233536466445;4666.510016448292;4690.061987602493;4692.718436893797;1796.1558067708156;1821.480877949778;1898.6202759448165;1924.7497825282624;1969.1138987455702;939.4645052320249;948.5873644964463;1022.0852337158202;1031.9742006449749;1101.6694991502518;19239.711404328948;19318.32385866488;20170.19319227244;20259.987000649908;21653.423668019965;1003.859459675646;1007.2495990486988;1029.6602121299952;1048.3762161513887;1078.4553800330782;9956.103950644478;11495.942520287344;11590.072794176614;11651.359175245443;11702.648920532398;623657;625657;656773;662825;668287;118717;120720.39639801744;121301;121451.57096858013;125652.37173814255;1058.751193469534;1060.750723579958;1072.495926914349;1076.7361996310954;1083.014456844268;18939.62120757605;19278;19345.90327048356;19538.706919396504;19716.40850774479;51857;53360.46639533555;53907;54025.45974540204;54488.45511544834;73481.6325918367;75970;76995;77143;77714;8081.232282933112;8297.460665056993;8370.204103977983;8423.70517031901;8622.78443038929;12526.436310365872;13519.121257118255;13679;13717.245551494763;14201.573952781433;11682.88317116818;11712.824307635221;11769.823452648388;11863.228890122115;11927.582534611247;9676.75808104803;11625.24435911663;11636.952674259357;11666.475008624802;11881.643550693492;11824;11941;12010.699732506757;12014.579489717818;12100.971417429266;8475.703350382708;8479.109693482342;8500.659973600987;8659.913400865911;8698.21716045559;10503.317284376491;10562.31344962575;11027.448627568428;11801.881981180422;12178.451969661564 13:08:19 INFO - PROCESS | 5976 | |3;mootools.html;1644.8355164483548;1646.8600168985636;1738;2106.1045059446506;2119.4277545062832;6959.965200173992;7643.770686879388;7848.175941526136;9095.772070770445;9241.953790231048;7602.353799926999;8924.732258032253;8943.41867778594;9038.096190380958;9076.546172691373;2565.6279839423273;2599.3111825366295;2601.5707408277613;2606.8566228857403;2610.6606141201714;2439.402346425128;2538.708048574419;2582.1866112174603;2584.7673709366154;2588.5599448093867;2315.4211447138214;2538.1497198438547;2583.095916429244;2583.922482325533;2598.103654239294;2548.949021019569;2557.411795287085;2576.7680908718307;2581.3288544978254;2583.6253743207226;2511.6483692283095;2565.7819085377764;2573.3052075939577;2580.6645136132183;2585.61215817627;9114.77213069672;9129.452232865982;9178.449293042371;9180.770480738034;9193.632254709804;5605.943940560583;5718.685472299022;5761.769529218826;5766;5774.047282198432;3627.2564124354444;3690.760100593453;3708.1841994761107;3781;3799.905002374935;1063.4416931111152;1068.4057166643506;1072.7264547540406;1082.0802318029691;1101.124605938281;11951;12112.39438028095;12148.3318417487;12271.38643067843;12275.754484910252;4981.501849815062;4989.201727723546;5083.949160508348;5121.615878809104;5123.6925784452305;10218.131458825934;10241.74395640115;10400.843987340197;10404.739881503023;10426.374417534973;5635.830925072254;5795.130730390475;5821.155932389865;5826.126081087871;5827.24245848037;4663.300504924288;5088.6946783193125;5172.120739474299;5228.346456692913;5229.267902493654;4038.63652271297;4093.7543747375253;4124.278251306009;4128.690348223895;4136.689748268892;1190.6666133482638;1364.222393235846;1367.3573420492353;1375.6492094515834;1387.049870838479;1387.9514217002356;1392.6031081142028;1437.058726534122;1456.5921541968266;1464.077631092405;8364.958175209085;8483.618237179342;8514.063453020162;8527.829443411221;8558.82882342362;534.7059117485368;535.844605064527;539.5386944162763;542.940276569577;544.6350944866948;6242.469390101802;6319.494440444753;6366.6180029198395;6378.649174295459;6397.584157029778;10788.622398216025;11095.167862410352;11100.777984440427;11145;11218.326900386002;10573.365598064142;10918.454077296263;10935.781284374269;10940.070094041936;11044.89551044889;8602.698905538276;8617.138286171457;8623.784405389915;8625.137486251324;8668.133186681282;7776.533407995538;8033.799155021171;8061.959690201512;8078.757637270891;8161.755147345589;9574.712758617252;9736.123748862636;9771.316007879665;9788.706338809847;9989.650362237287;8528.275096616857;9702.70891873245;10139.594416223268;10145.797084058426;10446.268761186644;6910.792676219721;7505.211952745101;7564.130125035471;7631;7718.92281077182;8280.254777069851;8295.875561866698;8425.578721064045;8444.535550544779;8464.111268316738;7387.150477695134;7601.7719468416035;7635.656395462329;7691.2308769124875;7708.537487750753;7434.814129646802;7466.589337586485;7504.249575042452;7540.622968851645;7587.810304742426;6842.144731908512;7978.281954624112;7984.6806127754235;7991.24083212094;8040.3969702273735;7101.076860008373;7248.96375518119;7254.927450725425;7306;7309.451791115794;7348.0080189175915;7369.705211791468;7382.741604043833;7391.8152046199275;7405.9629701851145 13:08:19 INFO - PROCESS | 5976 | |4;prototype.html;290.3192014725471;302.7456936173614;340.6236109099445;346.85778830679413;350.79829098268493;4782.067496838118;5445.373782015069;5481.780728770849;6651.60090394576;6809.114815074034;5841.474267315941;6725.798226053214;6847.212570554375;6853.417459515935;6891.793246202622;4855.198892182786;4931.284963680264;5026.949730502684;5027.49725027498;5130.743462826862;4097.405876147956;4999.500049995007;5027.49725027498;5031.195008798593;5031.195008798593;4953.628477864157;4956.529129732669;4975.477574854643;4978.9751051244875;5011.398632164145;4368.9514516515965;4753.524647535219;5051.368578927634;5053.5451809337155;5069.898602027975;4346.673999450054;4360.978195109036;4366.978165109186;4369.912601747979;4382.9342559861625;6255.499560035186;6571.507136964699;6601.933980660228;6617.371349721769;6642.634655093968;5873.914760260089;5877.05967045275;5911.408859114097;5911.940880591225;5916.645001299936;7366.337029667356;7368.926310736932;7372.7050917963215;7386.372158366546;7389.815254618624;4733.053389322149;4749.786259618326;4750.738709370983;4756.001239739624;4796.3524924135145;7378.372838308804;7738.071431428265;7799.142094369615;7805.609719514002;7817.257360550739;3860.5946375630715;4686.297055441711;4829.637777166727;4839.322494850724;4839.516048395132;4902.823322402598;4911.68074075184;4922.409310882717;4928.137575924199;4931.802727890916;5887.264091988502;5916.73374698131;5927.436893495111;5929.673867937218;5935.109733540004;7402.518836275625;7403.481756277009;7409.444291678145;7414.925850741423;7426.071741032371;1497.3711041362653;1631.1599526243995;1681.1090122235235;1708.9658206836043;1711.3753479979719;890.7773056735816;1053.073295499955;1080.6271836216492;1082.9891701082888;1087.1302957633861;6941.548799328028;7005.614691192033;7006.4394848412;7034.2965703429245;7048.612326321999;7672.117706463717;7672.73145439907;7703.383729301642;7711.652975616111;7746.883796743054;6979.40675042627;6993.216729733886;7003.544769590062;7025.981232721227;7026.981087742351;3203.8077715337154;3958.386450100239;4007.979960100239;4021.0349516116507;4029.234445455354;6317.494600432046;7421.6269990052015;7457.254274572608;8182.549959752271;8222.424430289942;8002.239787220205;8055.1542088079905;8065.516069035877;8081.393895457864;8127.512349259064;8190.631421586043;8203.75388738327;8205.507669539847;8237.588120593946;8242.546659933761;7394.704211831467;7679.193684663253;7760.88358674609;7799.181085986117;7813.531188128731;6218.9378106218355;6701.497387695845;7028.894566581607;7601.733939312097;7621.542707437571;8157.696133066118;8177.713780017671;8183;8183.036071342954;8254.958725206336;4759.929015971517;4835.685680430761;4872.317875497504;4877.707337559757;4886.438059623189;6482.416582507409;7297.343239108506;7471.290227428385;7734.651940662571;7753.534787912743;7368.226336234618;7461.925380746123;7468.551886886804;7471.178170401143;7472.439567032386;7358.77923662291;7437.88431735244;7448.366888814295;7453.9254607455405;7458.962705186439;5839.649621022752;6966.3730264276455;7265.963670181615;7290;7345.522541034816;7400.888986665097;7420.220876807857;7444.404447644284;7465.514741541782;7472.663730132266;7241.167265764505;7291.453141014551;7292.635368231461;7294.1247050354295;7295.7081716732855 13:08:19 INFO - PROCESS | 5976 | |5;yui.html;713.1762813949887;734.8309888725593;767.838753861689;792.5126047480798;799.9880001799974;15455.768163477556;15533.533993980185;15710.921445392756;15727.370905163794;15740.212989350544;14454.349554270084;14525.27373631317;14542.0547664402;14594.343254553543;14666.56000319992;991.1476130527758;1000.5897581991385;1002.1882275356984;1008.4554340656036;1013.7668336282659;981.4454833019357;1002.9749256268615;1012.7518757904323;1012.8987101289886;1014.0974532665933;1055.7044027672264;1057.3497299161;1060.5863713151878;1061.4692653673164;1063.261033581661;1046.2257929132463;1047.6385646951828;1051.8106740786654;1054.3252318516156;1054.362110922892;766.8581312457213;780.4029917113413;787.1695361393728;788.6648174525803;789.7314912929631;13761.036727429084;13892.23592702401;13996.580102596938;14082.859171408258;14095.718085638333;11810.350430726307;11882.881171188264;11926;11986.280823150551;12046.69883252917;9555.761105972406;9609.51952402384;9667.903320966841;9747.951260243653;9801.16690081344;3500.1249687578106;3510.8946731598094;3522.964770352289;3536.1159710072484;3636.6726994570618;75599.62200188964;76320;76778.23221767767;77257;79848.00379990488;9951.80096398068;10000.549975251131;10100.545475453622;10149.39103653783;10209.183265338756;22606;22664.433389165402;23439.179628712915;23689.881550592138;23700.7629923702;7901.960490197513;7981.760547183594;8047.637856296481;8113.269805717396;8178.836423271501;4118.855840045584;4301.096769678403;4336;4364.367166760802;4425.181341451842;3760.6615404613717;3764.2095160016156;3771.4908487354405;3782.413725872494;3788.7158463115375;686.2382755141789;692.0899017791588;695.4575431163701;697.5744795674634;711.2070041903344;511.4425276448689;593.6319481921237;601.4707057789118;616.1620196532801;639.8944174211296;7203.6400263971545;8439.831203375898;8561.0582835888;8608.880845490046;8772.95613521941;542.3708498142137;562.4178974761103;612.6630353305753;623.8128561431644;624.1148848418558;3968.3650615901315;4008.939865902014;4014.217227640582;4077.9796101019306;4163.042500224905;94142;96159;97449.51275243721;98069.50965245128;99267.02931882643;71275.64362178156;82356.58821705973;83986;84274.5786271077;84325.73511397334;60971.6951415249;63429.36570634327;67014;67274;68025;8578.871316930126;8703.86944195825;8796.736097917073;8797.516136612292;8864.911350886408;48331;49829.26963399255;51828.74085629548;52238.73880630724;53876.73061634667;72004.63997679978;72106;72265.6386718063;72928.63535682288;73975.1506212349;11444.82832757526;11669.59156429521;11673.12451566119;11703.824442633197;11959.521619135137;12127.45426455794;12288.692782680504;12360.196587221802;12490.687732806753;12772;9865.457399843077;11661.300321980709;11674.883251167379;11855.466504007169;12413.93793031029;11078.390688512209;11632.127590430582;11686.649400517997;11723.35521546323;12309.753804923672;11369.431528423711;11372.829407558727;11381.601643942422;11555.537778488766;12151.270923744603;9750.219982401533;9991.600335986479;9995.100440960348;10132.290739648448;10391.168706503311;9882.209423246266;10002.299839011199;10038.598456061967;10288.742781430225;10452.216083793897 13:08:19 INFO - PROCESS | 5976 | __end_tp_report 13:08:19 INFO - PROCESS | 5976 | __start_cc_report 13:08:19 INFO - PROCESS | 5976 | _x_x_mozilla_cycle_collect,355 13:08:19 INFO - PROCESS | 5976 | __end_cc_report 13:08:19 INFO - PROCESS | 5976 | __startTimestamp1463774899018__endTimestamp 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | ------- Summary: start ------- 13:08:19 INFO - PROCESS | 5976 | Number of tests: 6 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | [#0] dojo.html Cycles:165 Average:5762.11 Median:6031.94 stddev:642.39 (10.6%) stddev-sans-first:611.05 13:08:19 INFO - PROCESS | 5976 | Values: 3159.4 3608.5 3632.5 3633.1 3672.2 5842.4 5860.4 5869.6 5892.5 5919.5 5756.1 5802.1 5808.8 5859.8 5860.1 5252.9 5280.2 5333.9 5361.5 5409.1 4313.8 4981.7 5017.3 5351.5 5368.3 5238.9 5293.4 5310.5 5411.2 5421.8 5378.0 5379.6 5383.3 5384.5 5393.6 4987.4 5247.3 5269.3 5315.7 5366.4 4890.8 5590.0 5631.7 5652.4 5691.7 6202.4 6206.7 6218.1 6228.4 6228.8 6121.1 6149.2 6187.5 6190.0 6241.3 5983.6 5994.1 5997.2 6010.8 6019.8 6286.7 6308.5 6311.7 6312.0 6318.8 6087.1 6102.7 6135.0 6143.6 6178.0 5262.6 5417.5 6085.7 6117.1 6202.1 6083.6 6158.4 6160.7 6160.7 6176.9 6131.3 6142.1 6178.6 6196.3 6203.6 4941.1 4956.5 4961.3 4969.5 4976.5 4295.3 4415.4 4497.0 4502.9 4565.2 5887.4 5969.2 5993.9 6025.4 6031.9 6200.2 6217.1 6235.0 6240.4 6268.7 5854.6 5855.6 6018.5 6060.3 6086.5 5426.0 5428.8 5439.0 5460.0 5493.7 6378.6 6394.2 6395.3 6437.1 6529.0 6156.5 6156.5 6181.3 6218.2 6238.5 6413.7 6428.2 6434.2 6446.6 6459.7 6209.3 6209.4 6212.4 6215.4 6220.8 5867.3 6152.0 6205.3 6219.3 6220.6 6161.5 6169.7 6204.9 6205.8 6228.5 5976.3 6075.9 6186.0 6241.8 6254.6 5294.9 5385.4 5979.2 6113.0 6170.6 4769.7 5114.7 6177.5 6186.7 6237.0 4659.8 6102.3 6189.0 6212.4 6253.4 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | [#1] ext.html Cycles:180 Average:62864.04 Median:40015.60 stddev:129270.50 (323.1%) stddev-sans-first:129548.23 13:08:19 INFO - PROCESS | 5976 | Values: 458.6 538.2 580.8 592.7 663.0 31380.4 40015.4 40015.8 40289.0 40299.6 41201.0 41224.6 41823.2 41890.2 41950.4 9507.2 9627.4 9651.6 9679.3 9750.2 9161.3 9263.2 9269.8 9335.4 9399.0 8900.9 9220.0 9259.3 9390.2 9419.3 9007.5 9163.7 9217.0 9365.8 9414.2 9790.1 9885.7 9908.1 9981.4 9982.9 40118.4 40241.2 40567.4 40828.6 40923.8 3050.5 3297.3 3376.6 3458.2 3551.9 27899.0 28003.4 28069.2 28225.7 28790.9 2802.1 2832.5 2846.2 2860.5 2920.4 444261.0 445118.0 447458.0 448738.0 451146.0 47056.5 65205.0 65403.7 65639.7 65848.7 46580.8 46587.0 46760.0 46799.5 46806.5 17516.4 17526.0 17569.0 17801.7 17814.0 13981.9 14037.4 14246.6 14301.1 14355.6 15301.0 15424.2 15430.5 15488.8 15550.9 5568.4 6471.0 6512.8 6558.3 6558.6 993.8 1039.7 1058.0 1182.5 1195.4 26134.3 30224.5 30676.8 30821.5 30859.9 1013.3 1122.7 1149.8 1188.9 1230.4 11820.7 12483.7 13955.9 14780.3 16863.2 496957.0 498687.0 499315.0 502238.0 503156.5 52960.7 62613.3 62711.0 62745.0 63285.0 1530.4 1557.8 1560.0 1564.5 1695.7 28656.7 29970.6 30573.4 31373.8 31814.0 53554.0 53603.7 53717.0 54071.2 54434.7 507107.0 507530.0 508020.0 509534.0 515353.0 33533.7 34650.8 34719.0 34734.1 35123.6 45811.9 46350.5 46565.1 47058.8 47580.5 31941.0 37177.4 37710.4 38406.6 38742.8 37637.8 37844.2 37880.8 38090.6 38336.0 32418.0 32947.7 36012.8 36555.6 37805.5 25675.9 32374.0 33562.0 33989.2 34261.7 25127.6 25263.2 25304.5 25527.5 25560.7 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | [#2] jquery.html Cycles:180 Average:36099.40 Median:12161.94 stddev:107359.38 (882.7%) stddev-sans-first:107626.97 13:08:19 INFO - PROCESS | 5976 | Values: 338.4 383.8 391.4 392.5 393.7 20500.2 22181.7 22542.8 23153.9 23270.9 22147.7 22280.9 22406.7 22516.8 22534.3 5677.7 5890.4 5932.1 6202.6 6216.7 4086.6 5802.4 5988.7 6074.3 6080.9 5124.3 6055.4 6064.8 6071.9 6078.1 5632.5 5779.6 5804.6 5914.7 5926.8 4925.2 5363.4 5539.8 5675.2 5692.7 13399.1 14255.2 14408.8 21639.8 25450.7 2169.8 2176.2 2237.3 2389.0 2605.1 10254.4 10510.8 10518.3 11828.6 15198.2 1804.5 1848.3 1863.6 1866.1 1874.8 106944.6 109663.4 111429.0 115431.4 116956.5 9435.0 9533.2 11458.4 11738.4 11843.7 14522.1 14559.7 14566.9 14632.3 14688.9 9151.5 9160.5 9235.3 9293.0 9375.8 9513.6 9520.2 9583.0 9587.0 9679.0 3886.7 4645.2 4666.5 4690.1 4692.7 1796.2 1821.5 1898.6 1924.7 1969.1 939.5 948.6 1022.1 1032.0 1101.7 19239.7 19318.3 20170.2 20260.0 21653.4 1003.9 1007.2 1029.7 1048.4 1078.5 9956.1 11495.9 11590.1 11651.4 11702.6 623657.0 625657.0 656773.0 662825.0 668287.0 118717.0 120720.4 121301.0 121451.6 125652.4 1058.8 1060.8 1072.5 1076.7 1083.0 18939.6 19278.0 19345.9 19538.7 19716.4 51857.0 53360.5 53907.0 54025.5 54488.5 73481.6 75970.0 76995.0 77143.0 77714.0 8081.2 8297.5 8370.2 8423.7 8622.8 12526.4 13519.1 13679.0 13717.2 14201.6 11682.9 11712.8 11769.8 11863.2 11927.6 9676.8 11625.2 11637.0 11666.5 11881.6 11824.0 11941.0 12010.7 12014.6 12101.0 8475.7 8479.1 8500.7 8659.9 8698.2 10503.3 10562.3 11027.4 11801.9 12178.5 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | [#3] mootools.html Cycles:180 Average:6204.85 Median:541.24 stddev:3248.19 (600.1%) stddev-sans-first:3239.22 13:08:19 INFO - PROCESS | 5976 | Values: 1644.8 1646.9 1738.0 2106.1 2119.4 6960.0 7643.8 7848.2 9095.8 9242.0 7602.4 8924.7 8943.4 9038.1 9076.5 2565.6 2599.3 2601.6 2606.9 2610.7 2439.4 2538.7 2582.2 2584.8 2588.6 2315.4 2538.1 2583.1 2583.9 2598.1 2548.9 2557.4 2576.8 2581.3 2583.6 2511.6 2565.8 2573.3 2580.7 2585.6 9114.8 9129.5 9178.4 9180.8 9193.6 5605.9 5718.7 5761.8 5766.0 5774.0 3627.3 3690.8 3708.2 3781.0 3799.9 1063.4 1068.4 1072.7 1082.1 1101.1 11951.0 12112.4 12148.3 12271.4 12275.8 4981.5 4989.2 5083.9 5121.6 5123.7 10218.1 10241.7 10400.8 10404.7 10426.4 5635.8 5795.1 5821.2 5826.1 5827.2 4663.3 5088.7 5172.1 5228.3 5229.3 4038.6 4093.8 4124.3 4128.7 4136.7 1190.7 1364.2 1367.4 1375.6 1387.0 1388.0 1392.6 1437.1 1456.6 1464.1 8365.0 8483.6 8514.1 8527.8 8558.8 534.7 535.8 539.5 542.9 544.6 6242.5 6319.5 6366.6 6378.6 6397.6 10788.6 11095.2 11100.8 11145.0 11218.3 10573.4 10918.5 10935.8 10940.1 11044.9 8602.7 8617.1 8623.8 8625.1 8668.1 7776.5 8033.8 8062.0 8078.8 8161.8 9574.7 9736.1 9771.3 9788.7 9989.7 8528.3 9702.7 10139.6 10145.8 10446.3 6910.8 7505.2 7564.1 7631.0 7718.9 8280.3 8295.9 8425.6 8444.5 8464.1 7387.2 7601.8 7635.7 7691.2 7708.5 7434.8 7466.6 7504.2 7540.6 7587.8 6842.1 7978.3 7984.7 7991.2 8040.4 7101.1 7249.0 7254.9 7306.0 7309.5 7348.0 7369.7 7382.7 7391.8 7406.0 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | [#4] prototype.html Cycles:180 Average:5990.66 Median:6828.16 stddev:1974.99 (28.9%) stddev-sans-first:1933.63 13:08:19 INFO - PROCESS | 5976 | Values: 290.3 302.7 340.6 346.9 350.8 4782.1 5445.4 5481.8 6651.6 6809.1 5841.5 6725.8 6847.2 6853.4 6891.8 4855.2 4931.3 5026.9 5027.5 5130.7 4097.4 4999.5 5027.5 5031.2 5031.2 4953.6 4956.5 4975.5 4979.0 5011.4 4369.0 4753.5 5051.4 5053.5 5069.9 4346.7 4361.0 4367.0 4369.9 4382.9 6255.5 6571.5 6601.9 6617.4 6642.6 5873.9 5877.1 5911.4 5911.9 5916.6 7366.3 7368.9 7372.7 7386.4 7389.8 4733.1 4749.8 4750.7 4756.0 4796.4 7378.4 7738.1 7799.1 7805.6 7817.3 3860.6 4686.3 4829.6 4839.3 4839.5 4902.8 4911.7 4922.4 4928.1 4931.8 5887.3 5916.7 5927.4 5929.7 5935.1 7402.5 7403.5 7409.4 7414.9 7426.1 1497.4 1631.2 1681.1 1709.0 1711.4 890.8 1053.1 1080.6 1083.0 1087.1 6941.5 7005.6 7006.4 7034.3 7048.6 7672.1 7672.7 7703.4 7711.7 7746.9 6979.4 6993.2 7003.5 7026.0 7027.0 3203.8 3958.4 4008.0 4021.0 4029.2 6317.5 7421.6 7457.3 8182.5 8222.4 8002.2 8055.2 8065.5 8081.4 8127.5 8190.6 8203.8 8205.5 8237.6 8242.5 7394.7 7679.2 7760.9 7799.2 7813.5 6218.9 6701.5 7028.9 7601.7 7621.5 8157.7 8177.7 8183.0 8183.0 8255.0 4759.9 4835.7 4872.3 4877.7 4886.4 6482.4 7297.3 7471.3 7734.7 7753.5 7368.2 7461.9 7468.6 7471.2 7472.4 7358.8 7437.9 7448.4 7453.9 7459.0 5839.6 6966.4 7266.0 7290.0 7345.5 7400.9 7420.2 7444.4 7465.5 7472.7 7241.2 7291.5 7292.6 7294.1 7295.7 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - PROCESS | 5976 | [#5] yui.html Cycles:180 Average:18640.60 Median:4098.42 stddev:26094.73 (636.7%) stddev-sans-first:26133.21 13:08:19 INFO - PROCESS | 5976 | Values: 713.2 734.8 767.8 792.5 800.0 15455.8 15533.5 15710.9 15727.4 15740.2 14454.3 14525.3 14542.1 14594.3 14666.6 991.1 1000.6 1002.2 1008.5 1013.8 981.4 1003.0 1012.8 1012.9 1014.1 1055.7 1057.3 1060.6 1061.5 1063.3 1046.2 1047.6 1051.8 1054.3 1054.4 766.9 780.4 787.2 788.7 789.7 13761.0 13892.2 13996.6 14082.9 14095.7 11810.4 11882.9 11926.0 11986.3 12046.7 9555.8 9609.5 9667.9 9748.0 9801.2 3500.1 3510.9 3523.0 3536.1 3636.7 75599.6 76320.0 76778.2 77257.0 79848.0 9951.8 10000.5 10100.5 10149.4 10209.2 22606.0 22664.4 23439.2 23689.9 23700.8 7902.0 7981.8 8047.6 8113.3 8178.8 4118.9 4301.1 4336.0 4364.4 4425.2 3760.7 3764.2 3771.5 3782.4 3788.7 686.2 692.1 695.5 697.6 711.2 511.4 593.6 601.5 616.2 639.9 7203.6 8439.8 8561.1 8608.9 8773.0 542.4 562.4 612.7 623.8 624.1 3968.4 4008.9 4014.2 4078.0 4163.0 94142.0 96159.0 97449.5 98069.5 99267.0 71275.6 82356.6 83986.0 84274.6 84325.7 60971.7 63429.4 67014.0 67274.0 68025.0 8578.9 8703.9 8796.7 8797.5 8864.9 48331.0 49829.3 51828.7 52238.7 53876.7 72004.6 72106.0 72265.6 72928.6 73975.2 11444.8 11669.6 11673.1 11703.8 11959.5 12127.5 12288.7 12360.2 12490.7 12772.0 9865.5 11661.3 11674.9 11855.5 12413.9 11078.4 11632.1 11686.6 11723.4 12309.8 11369.4 11372.8 11381.6 11555.5 12151.3 9750.2 9991.6 9995.1 10132.3 10391.2 9882.2 10002.3 10038.6 10288.7 10452.2 13:08:19 INFO - PROCESS | 5976 | -------- Summary: end -------- 13:08:19 INFO - PROCESS | 5976 | 13:08:19 INFO - TEST-INFO | 5976: exit 0 13:08:19 INFO - TEST-OK | dromaeo_css | took 1109264ms 13:08:19 INFO - TEST-START | kraken 13:08:19 INFO - operating with platform_type : mac_ 13:08:19 INFO - Initialising browser for kraken test... 13:08:19 INFO - TEST-INFO | started process 5981 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpAikqy1/profile http://localhost:50496/getInfo.html) 13:08:22 INFO - PROCESS | 5981 | __metrics Screen width/height:1600/1200 13:08:22 INFO - PROCESS | 5981 | colorDepth:24 13:08:22 INFO - PROCESS | 5981 | Browser inner width/height: 1024/689 13:08:22 INFO - PROCESS | 5981 | __metrics 13:08:22 ERROR - PROCESS | 5981 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 13:08:22 INFO - TEST-INFO | 5981: exit 0 13:08:22 INFO - Browser initialized. 13:08:22 INFO - Running cycle 1/1 for kraken test... 13:08:22 INFO - TEST-INFO | started process 5985 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpAikqy1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:08:34 INFO - PROCESS | 5985 | RSS: Main: 167976960 13:08:34 INFO - PROCESS | 5985 | 13:08:37 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50496/tests/kraken/driver.html?testName=audio-beat-detection) 13:08:37 INFO - PROCESS | 5985 | RSS: Main: 179499008 13:08:37 INFO - PROCESS | 5985 | 13:08:40 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50496/tests/kraken/driver.html?testName=audio-dft) 13:08:41 INFO - PROCESS | 5985 | RSS: Main: 188579840 13:08:41 INFO - PROCESS | 5985 | 13:08:44 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50496/tests/kraken/driver.html?testName=audio-fft) 13:08:45 INFO - PROCESS | 5985 | RSS: Main: 190877696 13:08:45 INFO - PROCESS | 5985 | 13:08:47 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50496/tests/kraken/driver.html?testName=audio-oscillator) 13:08:47 INFO - PROCESS | 5985 | RSS: Main: 192032768 13:08:47 INFO - PROCESS | 5985 | 13:08:49 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50496/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:08:50 INFO - PROCESS | 5985 | RSS: Main: 192540672 13:08:50 INFO - PROCESS | 5985 | 13:08:53 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50496/tests/kraken/driver.html?testName=imaging-darkroom) 13:08:53 INFO - PROCESS | 5985 | RSS: Main: 193130496 13:08:53 INFO - PROCESS | 5985 | 13:08:56 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50496/tests/kraken/driver.html?testName=imaging-desaturate) 13:08:57 INFO - PROCESS | 5985 | RSS: Main: 194232320 13:08:57 INFO - PROCESS | 5985 | 13:09:00 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50496/tests/kraken/driver.html?testName=json-parse-financial) 13:09:00 INFO - PROCESS | 5985 | RSS: Main: 194387968 13:09:00 INFO - PROCESS | 5985 | 13:09:02 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50496/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:09:03 INFO - PROCESS | 5985 | RSS: Main: 193835008 13:09:03 INFO - PROCESS | 5985 | 13:09:04 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:09:05 INFO - PROCESS | 5985 | RSS: Main: 193826816 13:09:05 INFO - PROCESS | 5985 | 13:09:07 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:09:08 INFO - PROCESS | 5985 | RSS: Main: 193863680 13:09:08 INFO - PROCESS | 5985 | 13:09:10 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:09:11 INFO - PROCESS | 5985 | RSS: Main: 193900544 13:09:11 INFO - PROCESS | 5985 | 13:09:14 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:09:14 INFO - PROCESS | 5985 | RSS: Main: 193974272 13:09:14 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | Cycle 1(1): loaded http://localhost:50496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50496/tests/kraken/driver.html?testName=ai-astar) 13:09:16 INFO - PROCESS | 5985 | RSS: Main: 195694592 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | __start_tp_report 13:09:16 INFO - PROCESS | 5985 | _x_x_mozilla_page_load 13:09:16 INFO - PROCESS | 5985 | _x_x_mozilla_page_load_details 13:09:16 INFO - PROCESS | 5985 | |i|pagename|runs| 13:09:16 INFO - PROCESS | 5985 | |0;ai-astar;116;109;115;123;168;183;113;112;115;114 13:09:16 INFO - PROCESS | 5985 | |1;audio-beat-detection;185;105;181;178;147;144;119;157;145;122 13:09:16 INFO - PROCESS | 5985 | |2;audio-dft;217;265;188;272;274;229;197;199;201;202 13:09:16 INFO - PROCESS | 5985 | |3;audio-fft;70;64;75;107;83;71;56;59;115;56 13:09:16 INFO - PROCESS | 5985 | |4;audio-oscillator;84;85;117;79;80;88;114;77;79;110 13:09:16 INFO - PROCESS | 5985 | |5;imaging-gaussian-blur;90;87;90;87;89;87;91;87;98;129 13:09:16 INFO - PROCESS | 5985 | |6;imaging-darkroom;94;97;97;96;95;116;96;96;99;96 13:09:16 INFO - PROCESS | 5985 | |7;imaging-desaturate;93;112;127;91;94;89;127;92;152;145 13:09:16 INFO - PROCESS | 5985 | |8;json-parse-financial;54;58;71;86;88;87;87;87;51;57 13:09:16 INFO - PROCESS | 5985 | |9;json-stringify-tinderbox;62;64;69;73;65;66;66;66;68;65 13:09:16 INFO - PROCESS | 5985 | |10;stanford-crypto-aes;86;80;74;81;102;105;71;109;109;109 13:09:16 INFO - PROCESS | 5985 | |11;stanford-crypto-ccm;130;103;132;128;139;134;125;129;135;133 13:09:16 INFO - PROCESS | 5985 | |12;stanford-crypto-pbkdf2;141;179;173;167;137;127;137;120;139;169 13:09:16 INFO - PROCESS | 5985 | |13;stanford-crypto-sha256-iterative;63;69;57;61;68;98;99;63;93;100 13:09:16 INFO - PROCESS | 5985 | __end_tp_report 13:09:16 INFO - PROCESS | 5985 | __start_cc_report 13:09:16 INFO - PROCESS | 5985 | _x_x_mozilla_cycle_collect,897 13:09:16 INFO - PROCESS | 5985 | __end_cc_report 13:09:16 INFO - PROCESS | 5985 | __startTimestamp1463774956696__endTimestamp 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | ------- Summary: start ------- 13:09:16 INFO - PROCESS | 5985 | Number of tests: 14 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#0] ai-astar Cycles:10 Average:126.80 Median:115.50 stddev:26.15 (22.6%) stddev-sans-first:27.45 13:09:16 INFO - PROCESS | 5985 | Values: 116.0 109.0 115.0 123.0 168.0 183.0 113.0 112.0 115.0 114.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:152.00 stddev:27.55 (18.1%) stddev-sans-first:25.82 13:09:16 INFO - PROCESS | 5985 | Values: 185.0 105.0 181.0 178.0 147.0 144.0 119.0 157.0 145.0 122.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#2] audio-dft Cycles:10 Average:224.40 Median:223.00 stddev:33.70 (15.1%) stddev-sans-first:35.64 13:09:16 INFO - PROCESS | 5985 | Values: 217.0 265.0 188.0 272.0 274.0 229.0 197.0 199.0 201.0 202.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#3] audio-fft Cycles:10 Average:75.60 Median:67.00 stddev:20.61 (30.8%) stddev-sans-first:21.76 13:09:16 INFO - PROCESS | 5985 | Values: 70.0 64.0 75.0 107.0 83.0 71.0 56.0 59.0 115.0 56.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:79.50 stddev:15.86 (20.0%) stddev-sans-first:16.60 13:09:16 INFO - PROCESS | 5985 | Values: 84.0 85.0 117.0 79.0 80.0 88.0 114.0 77.0 79.0 110.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:89.50 stddev:12.91 (14.4%) stddev-sans-first:13.63 13:09:16 INFO - PROCESS | 5985 | Values: 90.0 87.0 90.0 87.0 89.0 87.0 91.0 87.0 98.0 129.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:96.00 stddev:6.39 (6.7%) stddev-sans-first:6.60 13:09:16 INFO - PROCESS | 5985 | Values: 94.0 97.0 97.0 96.0 95.0 116.0 96.0 96.0 99.0 96.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#7] imaging-desaturate Cycles:10 Average:112.20 Median:90.00 stddev:24.02 (26.7%) stddev-sans-first:24.45 13:09:16 INFO - PROCESS | 5985 | Values: 93.0 112.0 127.0 91.0 94.0 89.0 127.0 92.0 152.0 145.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#8] json-parse-financial Cycles:10 Average:72.60 Median:86.50 stddev:16.02 (18.5%) stddev-sans-first:15.52 13:09:16 INFO - PROCESS | 5985 | Values: 54.0 58.0 71.0 86.0 88.0 87.0 87.0 87.0 51.0 57.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:3.03 (4.6%) stddev-sans-first:2.76 13:09:16 INFO - PROCESS | 5985 | Values: 62.0 64.0 69.0 73.0 65.0 66.0 66.0 66.0 68.0 65.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:72.50 stddev:15.63 (21.6%) stddev-sans-first:16.39 13:09:16 INFO - PROCESS | 5985 | Values: 86.0 80.0 74.0 81.0 102.0 105.0 71.0 109.0 109.0 109.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:132.50 stddev:9.89 (7.5%) stddev-sans-first:10.48 13:09:16 INFO - PROCESS | 5985 | Values: 130.0 103.0 132.0 128.0 139.0 134.0 125.0 129.0 135.0 133.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.90 Median:154.00 stddev:21.02 (13.6%) stddev-sans-first:22.10 13:09:16 INFO - PROCESS | 5985 | Values: 141.0 179.0 173.0 167.0 137.0 127.0 137.0 120.0 139.0 169.0 13:09:16 INFO - PROCESS | 5985 | 13:09:16 INFO - PROCESS | 5985 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:68.50 stddev:17.96 (26.2%) stddev-sans-first:18.31 13:09:16 INFO - PROCESS | 5985 | Values: 63.0 69.0 57.0 61.0 68.0 98.0 99.0 63.0 93.0 100.0 13:09:16 INFO - PROCESS | 5985 | -------- Summary: end -------- 13:09:16 INFO - PROCESS | 5985 | 13:09:17 INFO - TEST-INFO | 5985: exit 0 13:09:17 INFO - TEST-OK | kraken | took 57853ms 13:09:17 INFO - SUITE-END | took 1167s 13:09:17 INFO - Completed test suite (00:19:27) 13:09:17 INFO - javascript benchmark 13:09:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3159.4470967580673, 3608.512850765146, 3632.54593175853, 3633.146210640499, 3672.2288319452914, 5842.44496772807, 5860.413958604137, 5869.559783016275, 5892.469677729004, 5919.496842768364, 5756.136579513065, 5802.071668533035, 5808.767649294024, 5859.824205273838, 5860.120981852724, 5252.868678283045, 5280.207968804691, 5333.866653333659, 5361.51746342829, 5409.134538473845, 4313.849015284466, 4981.70475676325, 5017.297578339036, 5351.464853514656, 5368.30212072432, 5238.895222095556, 5293.444188360225, 5310.522053015228, 5411.188321751749, 5421.783128674848, 5378.0, 5379.596530260246, 5383.2732581101445, 5384.461553844623, 5393.649412788195, 4987.351644286219, 5247.344081989751, 5269.314989051399, 5315.707636080014, 5366.436524164946, 4890.755462226874, 5590.049691552405, 5631.6902570359025, 5652.406497317805, 5691.715414229272, 6202.41077097675, 6206.6586337751405, 6218.098375735493, 6228.439440450381, 6228.750849965996, 6121.117656725397, 6149.200603921507, 6187.535934804908, 6189.96905015472, 6241.282252540925, 5983.551233657493, 5994.130851026664, 5997.22036135304, 6010.759569617167, 6019.759209631609, 6286.711543256046, 6308.526860485482, 6311.715972781236, 6312.0, 6318.778842740574, 6087.147799308101, 6102.69486525672, 6134.957077260912, 6143.603994442171, 6178.0, 5262.60530460217, 5417.458254174551, 6085.695715214221, 6117.143599895929, 6202.100695399142, 6083.6045657032155, 6158.3841615838055, 6160.661163636043, 6160.691965401712, 6176.938230617726, 6131.315499379051, 6142.069075791497, 6178.567500275028, 6196.318404975539, 6203.627782333075, 4941.135301322254, 4956.504349565087, 4961.305417241589, 4969.527894849984, 4976.5023497649945, 4295.334223195409, 4415.4259946206785, 4497.033137875373, 4502.887427814266, 4565.178267911808, 5887.381824908323, 5969.2240008799445, 5993.910091348634, 6025.427584379477, 6031.9396806032255, 6200.193974783249, 6217.098520714561, 6234.9712297471315, 6240.375962403723, 6268.686565671698, 5854.648721076749, 5855.648661080349, 6018.488428483541, 6060.33336333003, 6086.543509236736, 5425.972870135703, 5428.837134885881, 5438.993786149575, 5459.972700136474, 5493.697846618395, 6378.553501254868, 6394.168758061515, 6395.328490508523, 6437.0988061671405, 6528.967355163289, 6156.538259630456, 6156.538259630635, 6181.289151747606, 6218.160548325918, 6238.500919926304, 6413.743450261937, 6428.164338636135, 6434.163558737334, 6446.645434501147, 6459.709313081016, 6209.285932117864, 6209.379062093935, 6212.409821066992, 6215.409536094064, 6220.7511699531515, 5867.266591676041, 6152.0, 6205.286392064971, 6219.346968568235, 6220.595661282002, 6161.507079433724, 6169.660668663267, 6204.937950620617, 6205.751769929152, 6228.4705800007505, 5976.342602313655, 6075.878482430238, 6186.041163619646, 6241.750329986749, 6254.562180647493, 5294.861604754996, 5385.434529374516, 5979.22270104901, 6112.99135642614, 6170.598911070766, 4769.73766442849, 5114.744262786771, 6177.505799535937, 6186.659733714689, 6236.968815155895, 4659.8136074558, 6102.328743838263, 6189.04069869171, 6212.4408803207925, 6253.405926436982], "unit": "score", "name": "dojo.html", "value": 5724.849178030778}, {"lowerIsBetter": false, "replicates": [458.5895623417042, 538.2491424462875, 580.8228490310455, 592.6710675575057, 662.9834254143649, 31380.372392552134, 40015.39976900349, 40015.79992100043, 40288.99277518056, 40299.597004029914, 41200.969975750624, 41224.58775412245, 41823.16353672925, 41890.16219675605, 41950.37074443886, 9507.19188868947, 9627.422354658744, 9651.565679544437, 9679.322447428684, 9750.171235444961, 9161.267098632126, 9263.166315031645, 9269.814603707922, 9335.393199442015, 9398.966113727485, 8900.910990890074, 9219.95390023049, 9259.305552083588, 9390.248780097576, 9419.293552983554, 9007.45955242681, 9163.725088247364, 9217.032211617785, 9365.81268374632, 9414.199793017602, 9790.06994335537, 9885.703428897144, 9908.10827025564, 9981.401115933068, 9982.900170998342, 40118.39822402666, 40241.195176096604, 40567.39148912769, 40828.59171408307, 40923.7953810229, 3050.4661684205175, 3297.3240485700376, 3376.64545222753, 3458.239187378773, 3551.8756843510355, 27899.0, 28003.439931201465, 28069.157925262276, 28225.71774282272, 28790.856045719636, 2802.1173330400857, 2832.546792513188, 2846.1603826871124, 2860.4708128996203, 2920.415916816624, 444261.0, 445118.0, 447458.0, 448738.0, 451146.0, 47056.5294347059, 65205.0, 65403.67298163479, 65639.67180164068, 65848.67075664687, 46580.76709616498, 46587.0, 46760.0, 46799.5320046802, 46806.53193468022, 17516.38692645751, 17526.0, 17569.0, 17801.7329740054, 17814.0, 13981.930090349482, 14037.438502459992, 14246.572602821725, 14301.141931484144, 14355.64110897215, 15301.005434646713, 15424.228788560527, 15430.5370838875, 15488.845111548966, 15550.922245388927, 5568.44315568448, 6471.0, 6512.772052978123, 6558.311377305409, 6558.639274839929, 993.7714325705128, 1039.7088815131776, 1057.962971296001, 1182.467889449751, 1195.3903509210365, 26134.34664133412, 30224.546631800542, 30676.76045143819, 30821.537676934862, 30859.919902803293, 1013.2856336282903, 1122.6968718446137, 1149.7642983188425, 1188.916775825684, 1230.4094034863315, 11820.70448238801, 12483.687907802378, 13955.88352931763, 14780.334884930204, 16863.241154148094, 496957.0, 498687.0, 499315.0, 502238.0, 503156.48421757657, 52960.735196323774, 62613.30400220002, 62711.0, 62745.0, 63285.0, 1530.4490383461946, 1557.758547425151, 1559.9844001560066, 1564.4837203722516, 1695.6778212139654, 28656.713432865403, 29970.550441743828, 30573.388532228786, 31373.843130785113, 31814.0, 53554.0, 53603.73198133984, 53717.0, 54071.18893216526, 54434.72782636061, 507107.0, 507530.0, 508020.0, 509534.0, 515353.0, 33533.664663353054, 34650.82674586611, 34719.0, 34734.13164670904, 35123.59505619747, 45811.85470363268, 46350.53649463462, 46565.06869862516, 47058.76470617625, 47580.524194759, 31941.041768746974, 37177.44233836549, 37710.434343485416, 38406.6159338403, 38742.80628596839, 37637.81181094077, 37844.243115138095, 37880.810595946845, 38090.61909380871, 38336.0, 32418.0, 32947.68209271602, 36012.81993590015, 36555.63444365629, 37805.487780488475, 25675.871620642523, 32374.0, 33562.0, 33989.150271243416, 34261.65738342684, 25127.623085654097, 25263.242102736946, 25304.493910122063, 25527.489450211262, 25560.744392555836], "unit": "score", "name": "ext.html", "value": 19577.636283907064}, {"lowerIsBetter": false, "replicates": [338.41961036821846, 383.80425982748807, 391.3972482377139, 392.5387669488351, 393.6850519584332, 20500.237375377914, 22181.667274990887, 22542.774572254293, 23153.884230578824, 23270.88364558179, 22147.667784983238, 22280.888595557, 22406.663900041513, 22516.77483225167, 22534.323970280875, 5677.716114194273, 5890.440408161217, 5932.080527518242, 6202.565820392574, 6216.720247588858, 4086.5913408659194, 5802.361740208574, 5988.670623115727, 6074.301455332649, 6080.939190608082, 5124.256982737501, 6055.424734650202, 6064.848378790543, 6071.908921366183, 6078.088286757, 5632.5212356949505, 5779.624324418899, 5804.564657650672, 5914.674692891889, 5926.792562260322, 4925.21196608541, 5363.410024897297, 5539.778408863681, 5675.233843431125, 5692.7153642318135, 13399.12905661129, 14255.215963122024, 14408.783868241984, 21639.783602163934, 25450.745492545022, 2169.7504786949544, 2176.2056849250066, 2237.2505210754416, 2389.008561446997, 2605.1142611511987, 10254.436006019667, 10510.78978420435, 10518.316309439939, 11828.645140645709, 15198.240087995666, 1804.5127815489875, 1848.2699333763223, 1863.5806943437753, 1866.0669665167416, 1874.8218919202654, 106944.55548448999, 109663.35504967431, 111429.0, 115431.42284288525, 116956.49130526098, 9435.0, 9533.237341012702, 11458.427078646035, 11738.354390508604, 11843.703907402383, 14522.128672279485, 14559.708805823824, 14566.907481938897, 14632.268386580628, 14688.853111468748, 9151.496667683341, 9160.450372977642, 9235.261179105655, 9293.0, 9375.812483750287, 9513.619455221713, 9520.238380929648, 9582.952085239529, 9587.0, 9678.95160524193, 3886.689064874803, 4645.233536466445, 4666.510016448292, 4690.061987602493, 4692.718436893797, 1796.1558067708156, 1821.480877949778, 1898.6202759448165, 1924.7497825282624, 1969.1138987455702, 939.4645052320249, 948.5873644964463, 1022.0852337158202, 1031.9742006449749, 1101.6694991502518, 19239.711404328948, 19318.32385866488, 20170.19319227244, 20259.987000649908, 21653.423668019965, 1003.859459675646, 1007.2495990486988, 1029.6602121299952, 1048.3762161513887, 1078.4553800330782, 9956.103950644478, 11495.942520287344, 11590.072794176614, 11651.359175245443, 11702.648920532398, 623657.0, 625657.0, 656773.0, 662825.0, 668287.0, 118717.0, 120720.39639801744, 121301.0, 121451.57096858013, 125652.37173814255, 1058.751193469534, 1060.750723579958, 1072.495926914349, 1076.7361996310954, 1083.014456844268, 18939.62120757605, 19278.0, 19345.90327048356, 19538.706919396504, 19716.40850774479, 51857.0, 53360.46639533555, 53907.0, 54025.45974540204, 54488.45511544834, 73481.6325918367, 75970.0, 76995.0, 77143.0, 77714.0, 8081.232282933112, 8297.460665056993, 8370.204103977983, 8423.70517031901, 8622.78443038929, 12526.436310365872, 13519.121257118255, 13679.0, 13717.245551494763, 14201.573952781433, 11682.88317116818, 11712.824307635221, 11769.823452648388, 11863.228890122115, 11927.582534611247, 9676.75808104803, 11625.24435911663, 11636.952674259357, 11666.475008624802, 11881.643550693492, 11824.0, 11941.0, 12010.699732506757, 12014.579489717818, 12100.971417429266, 8475.703350382708, 8479.109693482342, 8500.659973600987, 8659.913400865911, 8698.21716045559, 10503.317284376491, 10562.31344962575, 11027.448627568428, 11801.881981180422, 12178.451969661564], "unit": "score", "name": "jquery.html", "value": 9738.632703098476}, {"lowerIsBetter": false, "replicates": [1644.8355164483548, 1646.8600168985636, 1738.0, 2106.1045059446506, 2119.4277545062832, 6959.965200173992, 7643.770686879388, 7848.175941526136, 9095.772070770445, 9241.953790231048, 7602.353799926999, 8924.732258032253, 8943.41867778594, 9038.096190380958, 9076.546172691373, 2565.6279839423273, 2599.3111825366295, 2601.5707408277613, 2606.8566228857403, 2610.6606141201714, 2439.402346425128, 2538.708048574419, 2582.1866112174603, 2584.7673709366154, 2588.5599448093867, 2315.4211447138214, 2538.1497198438547, 2583.095916429244, 2583.922482325533, 2598.103654239294, 2548.949021019569, 2557.411795287085, 2576.7680908718307, 2581.3288544978254, 2583.6253743207226, 2511.6483692283095, 2565.7819085377764, 2573.3052075939577, 2580.6645136132183, 2585.61215817627, 9114.77213069672, 9129.452232865982, 9178.449293042371, 9180.770480738034, 9193.632254709804, 5605.943940560583, 5718.685472299022, 5761.769529218826, 5766.0, 5774.047282198432, 3627.2564124354444, 3690.760100593453, 3708.1841994761107, 3781.0, 3799.905002374935, 1063.4416931111152, 1068.4057166643506, 1072.7264547540406, 1082.0802318029691, 1101.124605938281, 11951.0, 12112.39438028095, 12148.3318417487, 12271.38643067843, 12275.754484910252, 4981.501849815062, 4989.201727723546, 5083.949160508348, 5121.615878809104, 5123.6925784452305, 10218.131458825934, 10241.74395640115, 10400.843987340197, 10404.739881503023, 10426.374417534973, 5635.830925072254, 5795.130730390475, 5821.155932389865, 5826.126081087871, 5827.24245848037, 4663.300504924288, 5088.6946783193125, 5172.120739474299, 5228.346456692913, 5229.267902493654, 4038.63652271297, 4093.7543747375253, 4124.278251306009, 4128.690348223895, 4136.689748268892, 1190.6666133482638, 1364.222393235846, 1367.3573420492353, 1375.6492094515834, 1387.049870838479, 1387.9514217002356, 1392.6031081142028, 1437.058726534122, 1456.5921541968266, 1464.077631092405, 8364.958175209085, 8483.618237179342, 8514.063453020162, 8527.829443411221, 8558.82882342362, 534.7059117485368, 535.844605064527, 539.5386944162763, 542.940276569577, 544.6350944866948, 6242.469390101802, 6319.494440444753, 6366.6180029198395, 6378.649174295459, 6397.584157029778, 10788.622398216025, 11095.167862410352, 11100.777984440427, 11145.0, 11218.326900386002, 10573.365598064142, 10918.454077296263, 10935.781284374269, 10940.070094041936, 11044.89551044889, 8602.698905538276, 8617.138286171457, 8623.784405389915, 8625.137486251324, 8668.133186681282, 7776.533407995538, 8033.799155021171, 8061.959690201512, 8078.757637270891, 8161.755147345589, 9574.712758617252, 9736.123748862636, 9771.316007879665, 9788.706338809847, 9989.650362237287, 8528.275096616857, 9702.70891873245, 10139.594416223268, 10145.797084058426, 10446.268761186644, 6910.792676219721, 7505.211952745101, 7564.130125035471, 7631.0, 7718.92281077182, 8280.254777069851, 8295.875561866698, 8425.578721064045, 8444.535550544779, 8464.111268316738, 7387.150477695134, 7601.7719468416035, 7635.656395462329, 7691.2308769124875, 7708.537487750753, 7434.814129646802, 7466.589337586485, 7504.249575042452, 7540.622968851645, 7587.810304742426, 6842.144731908512, 7978.281954624112, 7984.6806127754235, 7991.24083212094, 8040.3969702273735, 7101.076860008373, 7248.96375518119, 7254.927450725425, 7306.0, 7309.451791115794, 7348.0080189175915, 7369.705211791468, 7382.741604043833, 7391.8152046199275, 7405.9629701851145], "unit": "score", "name": "mootools.html", "value": 5007.317536507262}, {"lowerIsBetter": false, "replicates": [290.3192014725471, 302.7456936173614, 340.6236109099445, 346.85778830679413, 350.79829098268493, 4782.067496838118, 5445.373782015069, 5481.780728770849, 6651.60090394576, 6809.114815074034, 5841.474267315941, 6725.798226053214, 6847.212570554375, 6853.417459515935, 6891.793246202622, 4855.198892182786, 4931.284963680264, 5026.949730502684, 5027.49725027498, 5130.743462826862, 4097.405876147956, 4999.500049995007, 5027.49725027498, 5031.195008798593, 5031.195008798593, 4953.628477864157, 4956.529129732669, 4975.477574854643, 4978.9751051244875, 5011.398632164145, 4368.9514516515965, 4753.524647535219, 5051.368578927634, 5053.5451809337155, 5069.898602027975, 4346.673999450054, 4360.978195109036, 4366.978165109186, 4369.912601747979, 4382.9342559861625, 6255.499560035186, 6571.507136964699, 6601.933980660228, 6617.371349721769, 6642.634655093968, 5873.914760260089, 5877.05967045275, 5911.408859114097, 5911.940880591225, 5916.645001299936, 7366.337029667356, 7368.926310736932, 7372.7050917963215, 7386.372158366546, 7389.815254618624, 4733.053389322149, 4749.786259618326, 4750.738709370983, 4756.001239739624, 4796.3524924135145, 7378.372838308804, 7738.071431428265, 7799.142094369615, 7805.609719514002, 7817.257360550739, 3860.5946375630715, 4686.297055441711, 4829.637777166727, 4839.322494850724, 4839.516048395132, 4902.823322402598, 4911.68074075184, 4922.409310882717, 4928.137575924199, 4931.802727890916, 5887.264091988502, 5916.73374698131, 5927.436893495111, 5929.673867937218, 5935.109733540004, 7402.518836275625, 7403.481756277009, 7409.444291678145, 7414.925850741423, 7426.071741032371, 1497.3711041362653, 1631.1599526243995, 1681.1090122235235, 1708.9658206836043, 1711.3753479979719, 890.7773056735816, 1053.073295499955, 1080.6271836216492, 1082.9891701082888, 1087.1302957633861, 6941.548799328028, 7005.614691192033, 7006.4394848412, 7034.2965703429245, 7048.612326321999, 7672.117706463717, 7672.73145439907, 7703.383729301642, 7711.652975616111, 7746.883796743054, 6979.40675042627, 6993.216729733886, 7003.544769590062, 7025.981232721227, 7026.981087742351, 3203.8077715337154, 3958.386450100239, 4007.979960100239, 4021.0349516116507, 4029.234445455354, 6317.494600432046, 7421.6269990052015, 7457.254274572608, 8182.549959752271, 8222.424430289942, 8002.239787220205, 8055.1542088079905, 8065.516069035877, 8081.393895457864, 8127.512349259064, 8190.631421586043, 8203.75388738327, 8205.507669539847, 8237.588120593946, 8242.546659933761, 7394.704211831467, 7679.193684663253, 7760.88358674609, 7799.181085986117, 7813.531188128731, 6218.9378106218355, 6701.497387695845, 7028.894566581607, 7601.733939312097, 7621.542707437571, 8157.696133066118, 8177.713780017671, 8183.0, 8183.036071342954, 8254.958725206336, 4759.929015971517, 4835.685680430761, 4872.317875497504, 4877.707337559757, 4886.438059623189, 6482.416582507409, 7297.343239108506, 7471.290227428385, 7734.651940662571, 7753.534787912743, 7368.226336234618, 7461.925380746123, 7468.551886886804, 7471.178170401143, 7472.439567032386, 7358.77923662291, 7437.88431735244, 7448.366888814295, 7453.9254607455405, 7458.962705186439, 5839.649621022752, 6966.3730264276455, 7265.963670181615, 7290.0, 7345.522541034816, 7400.888986665097, 7420.220876807857, 7444.404447644284, 7465.514741541782, 7472.663730132266, 7241.167265764505, 7291.453141014551, 7292.635368231461, 7294.1247050354295, 7295.7081716732855], "unit": "score", "name": "prototype.html", "value": 5320.679015641056}, {"lowerIsBetter": false, "replicates": [713.1762813949887, 734.8309888725593, 767.838753861689, 792.5126047480798, 799.9880001799974, 15455.768163477556, 15533.533993980185, 15710.921445392756, 15727.370905163794, 15740.212989350544, 14454.349554270084, 14525.27373631317, 14542.0547664402, 14594.343254553543, 14666.56000319992, 991.1476130527758, 1000.5897581991385, 1002.1882275356984, 1008.4554340656036, 1013.7668336282659, 981.4454833019357, 1002.9749256268615, 1012.7518757904323, 1012.8987101289886, 1014.0974532665933, 1055.7044027672264, 1057.3497299161, 1060.5863713151878, 1061.4692653673164, 1063.261033581661, 1046.2257929132463, 1047.6385646951828, 1051.8106740786654, 1054.3252318516156, 1054.362110922892, 766.8581312457213, 780.4029917113413, 787.1695361393728, 788.6648174525803, 789.7314912929631, 13761.036727429084, 13892.23592702401, 13996.580102596938, 14082.859171408258, 14095.718085638333, 11810.350430726307, 11882.881171188264, 11926.0, 11986.280823150551, 12046.69883252917, 9555.761105972406, 9609.51952402384, 9667.903320966841, 9747.951260243653, 9801.16690081344, 3500.1249687578106, 3510.8946731598094, 3522.964770352289, 3536.1159710072484, 3636.6726994570618, 75599.62200188964, 76320.0, 76778.23221767767, 77257.0, 79848.00379990488, 9951.80096398068, 10000.549975251131, 10100.545475453622, 10149.39103653783, 10209.183265338756, 22606.0, 22664.433389165402, 23439.179628712915, 23689.881550592138, 23700.7629923702, 7901.960490197513, 7981.760547183594, 8047.637856296481, 8113.269805717396, 8178.836423271501, 4118.855840045584, 4301.096769678403, 4336.0, 4364.367166760802, 4425.181341451842, 3760.6615404613717, 3764.2095160016156, 3771.4908487354405, 3782.413725872494, 3788.7158463115375, 686.2382755141789, 692.0899017791588, 695.4575431163701, 697.5744795674634, 711.2070041903344, 511.4425276448689, 593.6319481921237, 601.4707057789118, 616.1620196532801, 639.8944174211296, 7203.6400263971545, 8439.831203375898, 8561.0582835888, 8608.880845490046, 8772.95613521941, 542.3708498142137, 562.4178974761103, 612.6630353305753, 623.8128561431644, 624.1148848418558, 3968.3650615901315, 4008.939865902014, 4014.217227640582, 4077.9796101019306, 4163.042500224905, 94142.0, 96159.0, 97449.51275243721, 98069.50965245128, 99267.02931882643, 71275.64362178156, 82356.58821705973, 83986.0, 84274.5786271077, 84325.73511397334, 60971.6951415249, 63429.36570634327, 67014.0, 67274.0, 68025.0, 8578.871316930126, 8703.86944195825, 8796.736097917073, 8797.516136612292, 8864.911350886408, 48331.0, 49829.26963399255, 51828.74085629548, 52238.73880630724, 53876.73061634667, 72004.63997679978, 72106.0, 72265.6386718063, 72928.63535682288, 73975.1506212349, 11444.82832757526, 11669.59156429521, 11673.12451566119, 11703.824442633197, 11959.521619135137, 12127.45426455794, 12288.692782680504, 12360.196587221802, 12490.687732806753, 12772.0, 9865.457399843077, 11661.300321980709, 11674.883251167379, 11855.466504007169, 12413.93793031029, 11078.390688512209, 11632.127590430582, 11686.649400517997, 11723.35521546323, 12309.753804923672, 11369.431528423711, 11372.829407558727, 11381.601643942422, 11555.537778488766, 12151.270923744603, 9750.219982401533, 9991.600335986479, 9995.100440960348, 10132.290739648448, 10391.168706503311, 9882.209423246266, 10002.299839011199, 10038.598456061967, 10288.742781430225, 10452.216083793897], "unit": "score", "name": "yui.html", "value": 7178.709405014406}], "name": "dromaeo_css", "value": 7702.156480777009}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 109.0, 115.0, 123.0, 168.0, 183.0, 113.0, 112.0, 115.0, 114.0], "unit": "score", "name": "ai-astar", "value": 126.8}, {"lowerIsBetter": true, "replicates": [185.0, 105.0, 181.0, 178.0, 147.0, 144.0, 119.0, 157.0, 145.0, 122.0], "unit": "score", "name": "audio-beat-detection", "value": 148.3}, {"lowerIsBetter": true, "replicates": [217.0, 265.0, 188.0, 272.0, 274.0, 229.0, 197.0, 199.0, 201.0, 202.0], "unit": "score", "name": "audio-dft", "value": 224.4}, {"lowerIsBetter": true, "replicates": [70.0, 64.0, 75.0, 107.0, 83.0, 71.0, 56.0, 59.0, 115.0, 56.0], "unit": "score", "name": "audio-fft", "value": 75.6}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 117.0, 79.0, 80.0, 88.0, 114.0, 77.0, 79.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 91.3}, {"lowerIsBetter": true, "replicates": [90.0, 87.0, 90.0, 87.0, 89.0, 87.0, 91.0, 87.0, 98.0, 129.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.5}, {"lowerIsBetter": true, "replicates": [94.0, 97.0, 97.0, 96.0, 95.0, 116.0, 96.0, 96.0, 99.0, 96.0], "unit": "score", "name": "imaging-darkroom", "value": 98.2}, {"lowerIsBetter": true, "replicates": [93.0, 112.0, 127.0, 91.0, 94.0, 89.0, 127.0, 92.0, 152.0, 145.0], "unit": "score", "name": "imaging-desaturate", "value": 112.2}, {"lowerIsBetter": true, "replicates": [54.0, 58.0, 71.0, 86.0, 88.0, 87.0, 87.0, 87.0, 51.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 72.6}, {"lowerIsBetter": true, "replicates": [62.0, 64.0, 69.0, 73.0, 65.0, 66.0, 66.0, 66.0, 68.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.4}, {"lowerIsBetter": true, "replicates": [86.0, 80.0, 74.0, 81.0, 102.0, 105.0, 71.0, 109.0, 109.0, 109.0], "unit": "score", "name": "stanford-crypto-aes", "value": 92.6}, {"lowerIsBetter": true, "replicates": [130.0, 103.0, 132.0, 128.0, 139.0, 134.0, 125.0, 129.0, 135.0, 133.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 128.8}, {"lowerIsBetter": true, "replicates": [141.0, 179.0, 173.0, 167.0, 137.0, 127.0, 137.0, 120.0, 139.0, 169.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.9}, {"lowerIsBetter": true, "replicates": [63.0, 69.0, 57.0, 61.0, 68.0, 98.0, 99.0, 63.0, 93.0, 100.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 77.1}], "name": "kraken", "value": 1556.7}]} 13:09:17 INFO - Return code: 0 13:09:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:09:17 ERROR - # TBPL SUCCESS # 13:09:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:09:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:09:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:09:17 INFO - 2016-05-20 13:09:17.968 screenresolution[5990:96971] starting screenresolution argv=screenresolution get 13:09:17 INFO - 2016-05-20 13:09:17.971 screenresolution[5990:96971] Display 0: 1600x1200x32@60 13:09:17 INFO - 2016-05-20 13:09:17.977 screenresolution[5991:96974] starting screenresolution argv=screenresolution list 13:09:17 INFO - Available Modes on Display 0 13:09:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:09:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:09:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:09:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:09:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:09:18 INFO - Intel Iris: 13:09:18 INFO - Chipset Model: Intel Iris 13:09:18 INFO - Type: GPU 13:09:18 INFO - Bus: Built-In 13:09:18 INFO - VRAM (Dynamic, Max): 1536 MB 13:09:18 INFO - Vendor: Intel (0x8086) 13:09:18 INFO - Device ID: 0x0a2e 13:09:18 INFO - Revision ID: 0x0009 13:09:18 INFO - Displays: 13:09:18 INFO - Display: 13:09:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:09:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:09:18 INFO - Display Serial Number: 1600x1200 60 13:09:18 INFO - Main Display: Yes 13:09:18 INFO - Mirror: Off 13:09:18 INFO - Online: Yes 13:09:18 INFO - Rotation: Supported 13:09:18 INFO - Return code: 0 13:09:18 INFO - Running post-action listener: _resource_record_post_action 13:09:18 INFO - Running post-run listener: _resource_record_post_run 13:09:19 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 520192; Write bytes: 510895104; Read time: 4; Write time: 1595 13:09:19 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 196133376; Write bytes: 197985792; Read time: 17976; Write time: 430 13:09:19 INFO - run-tests - Wall time: 1170s; CPU: 54.0%; Read bytes: 520192; Write bytes: 302747136; Read time: 4; Write time: 1127 13:09:19 INFO - Running post-run listener: _upload_blobber_files 13:09:19 INFO - Blob upload gear active. 13:09:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:09:19 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 13:09:19 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:09:19 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:09:19 INFO - (blobuploader) - INFO - Open directory for files ... 13:09:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:09:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:09:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:09:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:09:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:09:20 INFO - (blobuploader) - INFO - Done attempting. 13:09:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:09:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:09:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:09:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:09:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:09:21 INFO - (blobuploader) - INFO - Done attempting. 13:09:21 INFO - (blobuploader) - INFO - Iteration through files over. 13:09:21 INFO - Return code: 0 13:09:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:09:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:09:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/659f6884d38360a97899c0c5ee8d8e4dff72d0af6e673b17f9b7a9ee1c9cc7f5149905c00466fa7b50bd127bb3aa9794c4e5118625f4eefc95b57880ee018996", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11466331b289ec6fb761f5fa3e969a996b1ae761feae2dbf2493f0c29a6b9298df7ae64a2716f4e2ee625c65283b503b80edb6da148cce3b7a3d603a96235c7f"} 13:09:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:09:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:09:21 INFO - Contents: 13:09:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/659f6884d38360a97899c0c5ee8d8e4dff72d0af6e673b17f9b7a9ee1c9cc7f5149905c00466fa7b50bd127bb3aa9794c4e5118625f4eefc95b57880ee018996", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11466331b289ec6fb761f5fa3e969a996b1ae761feae2dbf2493f0c29a6b9298df7ae64a2716f4e2ee625c65283b503b80edb6da148cce3b7a3d603a96235c7f"} 13:09:21 INFO - Running post-run listener: copy_logs_to_upload_dir 13:09:21 INFO - Copying logs to upload dir... 13:09:21 INFO - mkdir: /builds/slave/test/build/upload/logs 13:09:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1326.820153 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2016-05-20 13:09:21.859094) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 13:09:21.862376) ========= 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.ACmWMORcLF/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.z9GDplOEva/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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/Mozilla-Beta/sha512/659f6884d38360a97899c0c5ee8d8e4dff72d0af6e673b17f9b7a9ee1c9cc7f5149905c00466fa7b50bd127bb3aa9794c4e5118625f4eefc95b57880ee018996", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11466331b289ec6fb761f5fa3e969a996b1ae761feae2dbf2493f0c29a6b9298df7ae64a2716f4e2ee625c65283b503b80edb6da148cce3b7a3d603a96235c7f"} build_url:https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011477 build_url: 'https://queue.taskcluster.net/v1/task/YdC_LL7xRTaL34x9Cql4CQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/659f6884d38360a97899c0c5ee8d8e4dff72d0af6e673b17f9b7a9ee1c9cc7f5149905c00466fa7b50bd127bb3aa9794c4e5118625f4eefc95b57880ee018996", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11466331b289ec6fb761f5fa3e969a996b1ae761feae2dbf2493f0c29a6b9298df7ae64a2716f4e2ee625c65283b503b80edb6da148cce3b7a3d603a96235c7f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 13:09:21.891688) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:09:21.891993) ========= 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.ACmWMORcLF/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.z9GDplOEva/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.005390 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:09:21.940203) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 13:09:21.940481) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 13:09:21.940786) ========= ========= Total master_lag: 0.17 =========