builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0377 starttime: 1466742377.53 results: success (0) buildid: 20160623195857 builduid: e71bc06063e946da9212186ce33a8a36 revision: a6696f118cb2a90fb4071fc428e37cf826256db6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:17.529865) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:17.530278) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:17.530551) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:17.693025) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:17.693289) ========= 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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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-06-23 21:26:17-- 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% 8.22M=0.001s 2016-06-23 21:26:18 (8.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.544036 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:18.269708) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:18.270005) ========= 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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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.020654 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:18.349402) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 21:26:18.349723) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6696f118cb2a90fb4071fc428e37cf826256db6 --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 a6696f118cb2a90fb4071fc428e37cf826256db6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ep9gE2iRMA/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.wJINXDJeZg/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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-06-23 21:26:18,429 truncating revision to first 12 chars 2016-06-23 21:26:18,429 Setting DEBUG logging. 2016-06-23 21:26:18,429 attempt 1/10 2016-06-23 21:26:18,429 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a6696f118cb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-23 21:26:19,615 unpacking tar archive at: mozilla-beta-a6696f118cb2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.470101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 21:26:19.841352) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:19.841628) ========= script_repo_revision: a6696f118cb2a90fb4071fc428e37cf826256db6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:19.841997) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:19.842262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 21:26:19.859915) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-06-23 21:26:19.860264) ========= /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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:26:20 INFO - MultiFileLogger online at 20160623 21:26:20 in /builds/slave/test 21:26:20 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 21:26:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:26:20 INFO - {'append_to_log': False, 21:26:20 INFO - 'base_work_dir': '/builds/slave/test', 21:26:20 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:26:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:26:20 INFO - 'branch': 'Mozilla-Beta', 21:26:20 INFO - 'buildbot_json_path': 'buildprops.json', 21:26:20 INFO - 'config_files': ('talos/mac_config.py',), 21:26:20 INFO - 'default_actions': ('clobber', 21:26:20 INFO - 'read-buildbot-config', 21:26:20 INFO - 'download-and-extract', 21:26:20 INFO - 'populate-webroot', 21:26:20 INFO - 'create-virtualenv', 21:26:20 INFO - 'install', 21:26:20 INFO - 'run-tests'), 21:26:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:26:20 INFO - 'download_minidump_stackwalk': True, 21:26:20 INFO - 'download_symbols': 'ondemand', 21:26:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:26:20 INFO - 'tooltool.py': '/tools/tooltool.py', 21:26:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:26:20 INFO - '/tools/misc-python/virtualenv.py')}, 21:26:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:26:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:26:20 INFO - 'installer_path': 'installer.exe', 21:26:20 INFO - 'log_level': 'info', 21:26:20 INFO - 'log_name': 'talos', 21:26:20 INFO - 'log_to_console': True, 21:26:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:26:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:26:20 INFO - 'opt_config_files': (), 21:26:20 INFO - 'pip_index': False, 21:26:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:26:20 INFO - 'cmd': ('bash', 21:26:20 INFO - '-c', 21:26:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:26:20 INFO - 'enabled': True, 21:26:20 INFO - 'halt_on_failure': False, 21:26:20 INFO - 'name': 'check_screen_resolution'},), 21:26:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:26:20 INFO - 'cmd': ('bash', 21:26:20 INFO - '-c', 21:26:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:26:20 INFO - 'enabled': True, 21:26:20 INFO - 'halt_on_failure': False, 21:26:20 INFO - 'name': 'check_screen_resolution'},), 21:26:20 INFO - 'run_cmd_checks_enabled': True, 21:26:20 INFO - 'sps_profile': False, 21:26:20 INFO - 'sps_profile_interval': 0, 21:26:20 INFO - 'suite': 'dromaeojs-e10s', 21:26:20 INFO - 'system_bits': '32', 21:26:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:26:20 INFO - 'title': 't-yosemite-r7-0377', 21:26:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:26:20 INFO - 'use_talos_json': True, 21:26:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:26:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:26:20 INFO - 'work_dir': 'build'} 21:26:20 INFO - ##### 21:26:20 INFO - ##### Running clobber step. 21:26:20 INFO - ##### 21:26:20 INFO - Running pre-action listener: _resource_record_pre_action 21:26:20 INFO - Running main action method: clobber 21:26:20 INFO - rmtree: /builds/slave/test/build 21:26:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:26:21 INFO - Running post-action listener: _resource_record_post_action 21:26:21 INFO - ##### 21:26:21 INFO - ##### Running read-buildbot-config step. 21:26:21 INFO - ##### 21:26:21 INFO - Running pre-action listener: _resource_record_pre_action 21:26:21 INFO - Running main action method: read_buildbot_config 21:26:21 INFO - Using buildbot properties: 21:26:21 INFO - { 21:26:21 INFO - "project": "", 21:26:21 INFO - "product": "firefox", 21:26:21 INFO - "script_repo_revision": "production", 21:26:21 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 21:26:21 INFO - "repository": "", 21:26:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 21:26:21 INFO - "buildid": "20160623195857", 21:26:21 INFO - "pgo_build": "False", 21:26:21 INFO - "basedir": "/builds/slave/test", 21:26:21 INFO - "buildnumber": 4, 21:26:21 INFO - "slavename": "t-yosemite-r7-0377", 21:26:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:26:21 INFO - "platform": "yosemite_r7", 21:26:21 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 21:26:21 INFO - "branch": "mozilla-beta", 21:26:21 INFO - "repo_path": "releases/mozilla-beta", 21:26:21 INFO - "revision": "a6696f118cb2a90fb4071fc428e37cf826256db6", 21:26:21 INFO - "stage_platform": "macosx64", 21:26:21 INFO - "builduid": "e71bc06063e946da9212186ce33a8a36", 21:26:21 INFO - "slavebuilddir": "test" 21:26:21 INFO - } 21:26:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 21:26:21 INFO - Running post-action listener: _resource_record_post_action 21:26:21 INFO - ##### 21:26:21 INFO - ##### Running download-and-extract step. 21:26:21 INFO - ##### 21:26:21 INFO - Running pre-action listener: _resource_record_pre_action 21:26:21 INFO - Running main action method: download_and_extract 21:26:21 INFO - mkdir: /builds/slave/test/build/tests 21:26: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')]} 21:26:21 INFO - https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:26:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 21:26:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 21:26:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 21:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 21:26:52 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json: timed out 21:26:52 INFO - retry: attempt #1 caught exception: timed out 21:26:52 INFO - retry: Failed, sleeping 30 seconds before retrying 21:27:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #2 21:27:24 INFO - Downloaded 1165 bytes. 21:27:24 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 21:27:24 INFO - Using the following test package requirements: 21:27:24 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 21:27:24 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 21:27:24 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 21:27:24 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 21:27:24 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 21:27:24 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 21:27:24 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 21:27:24 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 21:27:24 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 21:27:24 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 21:27:24 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 21:27:24 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 21:27:24 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 21:27:24 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 21:27:24 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 21:27:24 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 21:27:24 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 21:27:24 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 21:27: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')]} 21:27:24 INFO - https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:27:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 21:27:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 21:27:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 21:27:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 21:27:26 INFO - Downloaded 18344057 bytes. 21:27:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:27:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:27:29 INFO - Return code: 0 21:27:29 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 21:27:29 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')]} 21:27:29 INFO - https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:27:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 21:27:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 21:27:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 21:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 21:27:31 INFO - Downloaded 18344057 bytes. 21:27:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:27:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:27:34 INFO - Return code: 0 21:27:34 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')]} 21:27:34 INFO - https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:27:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 21:27:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 21:27:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 21:27:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 21:27:36 INFO - Downloaded 10840145 bytes. 21:27:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:27:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:27:36 INFO - Return code: 0 21:27:36 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')]} 21:27:36 INFO - https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 21:27:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 21:27:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 21:27:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 21:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 21:27:39 INFO - Downloaded 86228404 bytes. 21:27:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 21:27:39 INFO - mkdir: /builds/slave/test/properties 21:27:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:27:39 INFO - Writing to file /builds/slave/test/properties/build_url 21:27:39 INFO - Contents: 21:27:39 INFO - build_url:https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 21:27:40 INFO - Running post-action listener: _resource_record_post_action 21:27:40 INFO - Running post-action listener: set_extra_try_arguments 21:27:40 INFO - ##### 21:27:40 INFO - ##### Running populate-webroot step. 21:27:40 INFO - ##### 21:27:40 INFO - Running pre-action listener: _resource_record_pre_action 21:27:40 INFO - Running main action method: populate_webroot 21:27:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:27:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:27:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:27:40 INFO - '--tppagecycles', 21:27:40 INFO - '7'], 21:27:40 INFO - 'tests': ['tsvgm']}}, 21:27:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:27:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:27:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:27:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:27:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:27:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:27:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['damp', 'tps']}, 21:27:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'tests': ['damp', 'tps']}, 21:27:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['dromaeo_dom']}, 21:27:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:27:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['a11yr', 21:27:40 INFO - 'ts_paint', 21:27:40 INFO - 'tpaint', 21:27:40 INFO - 'sessionrestore', 21:27:40 INFO - 'sessionrestore_no_auto_restore', 21:27:40 INFO - 'tabpaint']}, 21:27:40 INFO - 'other-e10s': {'tests': ['a11yr', 21:27:40 INFO - 'ts_paint', 21:27:40 INFO - 'tpaint', 21:27:40 INFO - 'sessionrestore', 21:27:40 INFO - 'sessionrestore_no_auto_restore', 21:27:40 INFO - 'tabpaint']}, 21:27:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:27:40 INFO - 'ts_paint', 21:27:40 INFO - 'tpaint', 21:27:40 INFO - 'sessionrestore', 21:27:40 INFO - 'sessionrestore_no_auto_restore', 21:27:40 INFO - 'tabpaint']}, 21:27:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:27:40 INFO - 'ts_paint', 21:27:40 INFO - 'tpaint', 21:27:40 INFO - 'sessionrestore', 21:27:40 INFO - 'sessionrestore_no_auto_restore', 21:27:40 INFO - 'tabpaint']}, 21:27:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['a11yr', 21:27:40 INFO - 'ts_paint', 21:27:40 INFO - 'tpaint', 21:27:40 INFO - 'sessionrestore', 21:27:40 INFO - 'sessionrestore_no_auto_restore', 21:27:40 INFO - 'tabpaint']}, 21:27:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['a11yr', 21:27:40 INFO - 'ts_paint', 21:27:40 INFO - 'tpaint', 21:27:40 INFO - 'sessionrestore', 21:27:40 INFO - 'sessionrestore_no_auto_restore', 21:27:40 INFO - 'tabpaint']}, 21:27:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['tsvgx', 21:27:40 INFO - 'tsvgr_opacity', 21:27:40 INFO - 'tart', 21:27:40 INFO - 'tscrollx', 21:27:40 INFO - 'cart']}, 21:27:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:27:40 INFO - 'tsvgr_opacity', 21:27:40 INFO - 'tart', 21:27:40 INFO - 'tscrollx', 21:27:40 INFO - 'cart']}, 21:27:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'talos_options': ['--disable-e10s'], 21:27:40 INFO - 'tests': ['tp5o']}, 21:27:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'tests': ['tp5o']}, 21:27:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'talos_options': ['--disable-e10s', 21:27:40 INFO - '--xperf_path', 21:27:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:27:40 INFO - 'tests': ['tp5n']}, 21:27:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:27:40 INFO - 'talos_options': ['--xperf_path', 21:27:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:27:40 INFO - 'tests': ['tp5n']}}, 21:27:40 INFO - 'talos.zip': {'path': '', 21:27:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:27:40 INFO - Running post-action listener: _resource_record_post_action 21:27:40 INFO - ##### 21:27:40 INFO - ##### Running create-virtualenv step. 21:27:40 INFO - ##### 21:27:40 INFO - Running pre-action listener: _resource_record_pre_action 21:27:40 INFO - Running main action method: create_virtualenv 21:27:40 INFO - Creating virtualenv /builds/slave/test/build/venv 21:27:40 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 21:27:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:27:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:27:40 INFO - Using real prefix '/tools/python27' 21:27:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:27:41 INFO - Installing distribute.............................................................................................................................................................................................done. 21:27:44 INFO - Installing pip.................done. 21:27:44 INFO - Return code: 0 21:27:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:27:44 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')]} 21:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:44 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')]} 21:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:27:44 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 21:27:44 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 21:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:27:44 INFO - 'HOME': '/Users/cltbld', 21:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:27:44 INFO - 'LOGNAME': 'cltbld', 21:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:27:44 INFO - 'MOZ_NO_REMOTE': '1', 21:27:44 INFO - 'NO_EM_RESTART': '1', 21:27:44 INFO - 'PAGER': '/bin/cat', 21:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:27:44 INFO - 'PWD': '/builds/slave/test', 21:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:27:44 INFO - 'SHELL': '/bin/bash', 21:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:27:44 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:27:44 INFO - 'USER': 'cltbld', 21:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:27:44 INFO - 'XPC_FLAGS': '0x0', 21:27:44 INFO - 'XPC_SERVICE_NAME': '0', 21:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:27:44 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 21:27:44 INFO - Cleaning up... 21:27:44 INFO - Return code: 0 21:27:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:27:44 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')]} 21:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:44 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')]} 21:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:27:44 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 21:27:44 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 21:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:27:44 INFO - 'HOME': '/Users/cltbld', 21:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:27:44 INFO - 'LOGNAME': 'cltbld', 21:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:27:44 INFO - 'MOZ_NO_REMOTE': '1', 21:27:44 INFO - 'NO_EM_RESTART': '1', 21:27:44 INFO - 'PAGER': '/bin/cat', 21:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:27:44 INFO - 'PWD': '/builds/slave/test', 21:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:27:44 INFO - 'SHELL': '/bin/bash', 21:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:27:44 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:27:44 INFO - 'USER': 'cltbld', 21:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:27:44 INFO - 'XPC_FLAGS': '0x0', 21:27:44 INFO - 'XPC_SERVICE_NAME': '0', 21:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:27:44 INFO - Downloading/unpacking psutil>=0.7.1 21:27:44 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 21:27:44 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 21:27:44 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 21:27:44 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 21:27:44 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 21:27:44 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 21:27:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:27:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:27:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:27:48 INFO - Installing collected packages: psutil 21:27:48 INFO - Running setup.py install for psutil 21:27:48 INFO - building 'psutil._psutil_osx' extension 21:27:48 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 21:27:48 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 21:27:48 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 21:27:48 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 21:27:48 INFO - building 'psutil._psutil_posix' extension 21:27:48 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 21:27:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:27:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:27:48 INFO - ^ 21:27:48 INFO - 1 warning generated. 21:27:48 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 21:27:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:27:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:27:49 INFO - Successfully installed psutil 21:27:49 INFO - Cleaning up... 21:27:49 INFO - Return code: 0 21:27:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:27:49 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')]} 21:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:49 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')]} 21:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:27:49 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 21:27:49 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 21:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:27:49 INFO - 'HOME': '/Users/cltbld', 21:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:27:49 INFO - 'LOGNAME': 'cltbld', 21:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:27:49 INFO - 'MOZ_NO_REMOTE': '1', 21:27:49 INFO - 'NO_EM_RESTART': '1', 21:27:49 INFO - 'PAGER': '/bin/cat', 21:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:27:49 INFO - 'PWD': '/builds/slave/test', 21:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:27:49 INFO - 'SHELL': '/bin/bash', 21:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:27:49 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:27:49 INFO - 'USER': 'cltbld', 21:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:27:49 INFO - 'XPC_FLAGS': '0x0', 21:27:49 INFO - 'XPC_SERVICE_NAME': '0', 21:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:27:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:27:49 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 21:27:49 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 21:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:27:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:27:51 INFO - Installing collected packages: mozsystemmonitor 21:27:51 INFO - Running setup.py install for mozsystemmonitor 21:27:52 INFO - Successfully installed mozsystemmonitor 21:27:52 INFO - Cleaning up... 21:27:52 INFO - Return code: 0 21:27:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:27:52 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')]} 21:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:52 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')]} 21:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:27:52 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 21:27:52 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 21:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:27:52 INFO - 'HOME': '/Users/cltbld', 21:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:27:52 INFO - 'LOGNAME': 'cltbld', 21:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:27:52 INFO - 'MOZ_NO_REMOTE': '1', 21:27:52 INFO - 'NO_EM_RESTART': '1', 21:27:52 INFO - 'PAGER': '/bin/cat', 21:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:27:52 INFO - 'PWD': '/builds/slave/test', 21:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:27:52 INFO - 'SHELL': '/bin/bash', 21:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:27:52 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:27:52 INFO - 'USER': 'cltbld', 21:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:27:52 INFO - 'XPC_FLAGS': '0x0', 21:27:52 INFO - 'XPC_SERVICE_NAME': '0', 21:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:27:52 INFO - Downloading/unpacking blobuploader==1.2.4 21:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:27:52 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 21:27:52 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 21:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:27:54 INFO - Downloading blobuploader-1.2.4.tar.gz 21:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:27:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:27: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 21:27: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 21:27: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 21:27: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 21:27: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 21:27: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 21:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:27:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:27:55 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 21:27:55 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 21:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:27:56 INFO - Downloading docopt-0.6.1.tar.gz 21:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:27:56 INFO - Installing collected packages: blobuploader, requests, docopt 21:27:56 INFO - Running setup.py install for blobuploader 21:27:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:27:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:27:56 INFO - Running setup.py install for requests 21:27:56 INFO - Running setup.py install for docopt 21:27:56 INFO - Successfully installed blobuploader requests docopt 21:27:56 INFO - Cleaning up... 21:27:57 INFO - Return code: 0 21:27:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:27:57 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')]} 21:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:57 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')]} 21:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:27:57 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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:27:57 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 21:27:57 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 21:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:27:57 INFO - 'HOME': '/Users/cltbld', 21:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:27:57 INFO - 'LOGNAME': 'cltbld', 21:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:27:57 INFO - 'MOZ_NO_REMOTE': '1', 21:27:57 INFO - 'NO_EM_RESTART': '1', 21:27:57 INFO - 'PAGER': '/bin/cat', 21:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:27:57 INFO - 'PWD': '/builds/slave/test', 21:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:27:57 INFO - 'SHELL': '/bin/bash', 21:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:27:57 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:27:57 INFO - 'USER': 'cltbld', 21:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:27:57 INFO - 'XPC_FLAGS': '0x0', 21:27:57 INFO - 'XPC_SERVICE_NAME': '0', 21:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:27:57 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-7UbPK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:27:57 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-_fcbrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:27:57 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-BtOXe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:27:57 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-MyU6mA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:27:57 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-fBKcHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:27:57 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-msZS80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:27:57 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-x7PSQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-7UCjqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-KExAZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-KWgEJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-L7yxGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-ZhtnT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-oGKPcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-KY22VZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-T0QLbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-ehEm_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:27:58 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-wnQdO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:27:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:27:59 INFO - Running setup.py install for manifestparser 21:27:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:27:59 INFO - Running setup.py install for mozcrash 21:27:59 INFO - Running setup.py install for mozdebug 21:27:59 INFO - Running setup.py install for mozdevice 21:27:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:27:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:27:59 INFO - Running setup.py install for mozfile 21:27:59 INFO - Running setup.py install for mozhttpd 21:27:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:27:59 INFO - Running setup.py install for mozinfo 21:28:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Running setup.py install for mozInstall 21:28:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Running setup.py install for mozleak 21:28:00 INFO - Running setup.py install for mozlog 21:28:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Running setup.py install for moznetwork 21:28:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Running setup.py install for mozprocess 21:28:00 INFO - Running setup.py install for mozprofile 21:28:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:28:00 INFO - Running setup.py install for mozrunner 21:28:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:28:01 INFO - Running setup.py install for mozscreenshot 21:28:01 INFO - Running setup.py install for moztest 21:28:01 INFO - Running setup.py install for mozversion 21:28:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:28:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:28:01 INFO - Cleaning up... 21:28:01 INFO - Return code: 0 21:28:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28:01 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')]} 21:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:01 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')]} 21:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:01 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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:28:01 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 21:28:01 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 21:28:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:28:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:28:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:28:01 INFO - 'HOME': '/Users/cltbld', 21:28:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:28:01 INFO - 'LOGNAME': 'cltbld', 21:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:01 INFO - 'MOZ_NO_REMOTE': '1', 21:28:01 INFO - 'NO_EM_RESTART': '1', 21:28:01 INFO - 'PAGER': '/bin/cat', 21:28:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:01 INFO - 'PWD': '/builds/slave/test', 21:28:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:28:01 INFO - 'SHELL': '/bin/bash', 21:28:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:28:01 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:28:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:28:01 INFO - 'USER': 'cltbld', 21:28:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:28:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:01 INFO - 'XPC_FLAGS': '0x0', 21:28:01 INFO - 'XPC_SERVICE_NAME': '0', 21:28:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:28:01 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-x9QcWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:28:01 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)) 21:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:28:01 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-4theJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-gUPhF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Yf0wjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-kTnVyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-tnQ5m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-J031Gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-ASodv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-lxOKql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-uotUuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:28:02 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)) 21:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:28:02 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-KQjLwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:28:03 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)) 21:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:28:03 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-76TiNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:28:03 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)) 21:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:28:03 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-CVz6B0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:28:03 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)) 21:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:28:03 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-_A0Yks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:28:03 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)) 21:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:03 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Iz16oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:03 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)) 21:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:28:03 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-js8fip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:28:03 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)) 21:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:28:03 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Jr4neA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:28:03 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)) 21:28:03 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)) 21:28:03 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)) 21:28:03 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)) 21:28:03 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)) 21:28:03 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)) 21:28:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:03 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 21:28:03 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 21:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:06 INFO - Downloading blessings-1.6.tar.gz 21:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:28:06 INFO - Installing collected packages: blessings 21:28:06 INFO - Running setup.py install for blessings 21:28:06 INFO - Successfully installed blessings 21:28:06 INFO - Cleaning up... 21:28:06 INFO - Return code: 0 21:28:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:28:06 INFO - Reading from file tmpfile_stdout 21:28:06 INFO - Current package versions: 21:28:06 INFO - blessings == 1.6 21:28:06 INFO - blobuploader == 1.2.4 21:28:06 INFO - docopt == 0.6.1 21:28:06 INFO - manifestparser == 1.1 21:28:06 INFO - mozInstall == 1.12 21:28:06 INFO - mozcrash == 0.17 21:28:06 INFO - mozdebug == 0.1 21:28:06 INFO - mozdevice == 0.48 21:28:06 INFO - mozfile == 1.2 21:28:06 INFO - mozhttpd == 0.7 21:28:06 INFO - mozinfo == 0.9 21:28:06 INFO - mozleak == 0.1 21:28:06 INFO - mozlog == 3.1 21:28:06 INFO - moznetwork == 0.27 21:28:06 INFO - mozprocess == 0.22 21:28:06 INFO - mozprofile == 0.28 21:28:06 INFO - mozrunner == 6.11 21:28:06 INFO - mozscreenshot == 0.1 21:28:06 INFO - mozsystemmonitor == 0.0 21:28:06 INFO - moztest == 0.7 21:28:06 INFO - mozversion == 1.4 21:28:06 INFO - psutil == 3.1.1 21:28:06 INFO - requests == 1.2.3 21:28:06 INFO - wsgiref == 0.1.2 21:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:06 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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:28:06 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 21:28:06 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 21:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:28:06 INFO - 'HOME': '/Users/cltbld', 21:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:28:06 INFO - 'LOGNAME': 'cltbld', 21:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:06 INFO - 'MOZ_NO_REMOTE': '1', 21:28:06 INFO - 'NO_EM_RESTART': '1', 21:28:06 INFO - 'PAGER': '/bin/cat', 21:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:06 INFO - 'PWD': '/builds/slave/test', 21:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:28:06 INFO - 'SHELL': '/bin/bash', 21:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:28:06 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:28:06 INFO - 'USER': 'cltbld', 21:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:06 INFO - 'XPC_FLAGS': '0x0', 21:28:06 INFO - 'XPC_SERVICE_NAME': '0', 21:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 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)) 21:28:07 INFO - Cleaning up... 21:28:07 INFO - Return code: 0 21:28:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:28:07 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')]} 21:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:07 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')]} 21:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:07 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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:28:07 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 21:28:07 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 21:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:28:07 INFO - 'HOME': '/Users/cltbld', 21:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:28:07 INFO - 'LOGNAME': 'cltbld', 21:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:07 INFO - 'MOZ_NO_REMOTE': '1', 21:28:07 INFO - 'NO_EM_RESTART': '1', 21:28:07 INFO - 'PAGER': '/bin/cat', 21:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:07 INFO - 'PWD': '/builds/slave/test', 21:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:28:07 INFO - 'SHELL': '/bin/bash', 21:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:28:07 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:28:07 INFO - 'USER': 'cltbld', 21:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:07 INFO - 'XPC_FLAGS': '0x0', 21:28:07 INFO - 'XPC_SERVICE_NAME': '0', 21:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:07 INFO - Downloading/unpacking jsonschema 21:28:07 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 21:28:07 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 21:28:07 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 21:28:07 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 21:28:07 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 21:28:07 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 21:28:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:28:09 INFO - Downloading/unpacking functools32 (from jsonschema) 21:28:09 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 21:28:09 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 21:28:09 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 21:28:09 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 21:28:09 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 21:28:09 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 21:28:10 INFO - Downloading functools32-3.2.3-2.tar.gz 21:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:28:10 INFO - warning: no files found matching '*.txt' 21:28:10 INFO - no previously-included directories found matching 'build' 21:28:10 INFO - no previously-included directories found matching 'dist' 21:28:10 INFO - no previously-included directories found matching '.git*' 21:28:10 INFO - Installing collected packages: jsonschema, functools32 21:28:10 INFO - Running setup.py install for functools32 21:28:10 INFO - warning: no files found matching '*.txt' 21:28:10 INFO - no previously-included directories found matching 'build' 21:28:10 INFO - no previously-included directories found matching 'dist' 21:28:10 INFO - no previously-included directories found matching '.git*' 21:28:10 INFO - Successfully installed jsonschema functools32 21:28:10 INFO - Cleaning up... 21:28:10 INFO - Return code: 0 21:28:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:28:10 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')]} 21:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:10 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')]} 21:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:10 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 0x10d70da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d71d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc184916b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, '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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 21:28:10 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 21:28:10 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 21:28:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:28:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:28:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:28:10 INFO - 'HOME': '/Users/cltbld', 21:28:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:28:10 INFO - 'LOGNAME': 'cltbld', 21:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:10 INFO - 'MOZ_NO_REMOTE': '1', 21:28:10 INFO - 'NO_EM_RESTART': '1', 21:28:10 INFO - 'PAGER': '/bin/cat', 21:28:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:10 INFO - 'PWD': '/builds/slave/test', 21:28:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:28:10 INFO - 'SHELL': '/bin/bash', 21:28:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:28:10 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:28:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:28:10 INFO - 'USER': 'cltbld', 21:28:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:28:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:10 INFO - 'XPC_FLAGS': '0x0', 21:28:10 INFO - 'XPC_SERVICE_NAME': '0', 21:28:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:10 INFO - Downloading/unpacking flake8 21:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:10 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 21:28:10 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 21:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:28:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:28: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 21:28: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 21:28: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 21:28: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 21:28: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 21:28: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 21:28:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:28:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:28: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 21:28: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 21:28: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 21:28: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 21:28: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 21:28: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 21:28:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:14 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 21:28:14 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 21:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:28:14 INFO - no previously-included directories found matching 'docs/_build' 21:28:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:28:14 INFO - Running setup.py install for pep8 21:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:28:14 INFO - no previously-included directories found matching 'docs/_build' 21:28:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:28:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:28:14 INFO - Cleaning up... 21:28:14 INFO - Return code: 0 21:28:14 INFO - Running post-action listener: _resource_record_post_action 21:28:14 INFO - Running post-action listener: _start_resource_monitoring 21:28:14 INFO - Starting resource monitoring. 21:28:14 INFO - ##### 21:28:14 INFO - ##### Running install step. 21:28:14 INFO - ##### 21:28:14 INFO - Running pre-action listener: _resource_record_pre_action 21:28:14 INFO - Running main action method: install 21:28:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:28:15 INFO - Reading from file tmpfile_stdout 21:28:15 INFO - Detecting whether we're running mozinstall >=1.0... 21:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:28:15 INFO - Reading from file tmpfile_stdout 21:28:15 INFO - Output received: 21:28:15 INFO - Usage: mozinstall [options] installer 21:28:15 INFO - Options: 21:28:15 INFO - -h, --help show this help message and exit 21:28:15 INFO - -d DEST, --destination=DEST 21:28:15 INFO - Directory to install application into. [default: 21:28:15 INFO - "/builds/slave/test"] 21:28:15 INFO - --app=APP Application being installed. [default: firefox] 21:28:15 INFO - mkdir: /builds/slave/test/build/application 21:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 21:28:37 INFO - Reading from file tmpfile_stdout 21:28:37 INFO - Output received: 21:28:37 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 21:28:37 INFO - Running post-action listener: _resource_record_post_action 21:28:37 INFO - ##### 21:28:37 INFO - ##### Running run-tests step. 21:28:37 INFO - ##### 21:28:37 INFO - Running pre-action listener: _resource_record_pre_action 21:28:37 INFO - Running main action method: run_tests 21:28:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:28:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:28:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:28:37 INFO - 2016-06-23 21:28:37.580 screenresolution[2200:19635] starting screenresolution argv=screenresolution get 21:28:37 INFO - 2016-06-23 21:28:37.583 screenresolution[2200:19635] Display 0: 1600x1200x32@60 21:28:37 INFO - 2016-06-23 21:28:37.589 screenresolution[2201:19637] starting screenresolution argv=screenresolution list 21:28:37 INFO - Available Modes on Display 0 21:28:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:28:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:28:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:28:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:28:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:28:37 INFO - Intel Iris: 21:28:37 INFO - Chipset Model: Intel Iris 21:28:37 INFO - Type: GPU 21:28:37 INFO - Bus: Built-In 21:28:37 INFO - VRAM (Dynamic, Max): 1536 MB 21:28:37 INFO - Vendor: Intel (0x8086) 21:28:37 INFO - Device ID: 0x0a2e 21:28:37 INFO - Revision ID: 0x0009 21:28:37 INFO - Displays: 21:28:37 INFO - Display: 21:28:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:28:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:28:37 INFO - Display Serial Number: 1600x1200 60 21:28:37 INFO - Main Display: Yes 21:28:37 INFO - Mirror: Off 21:28:37 INFO - Online: Yes 21:28:37 INFO - Rotation: Supported 21:28:37 INFO - Return code: 0 21:28:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:28:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:28:37 INFO - Python 2.7.3 21:28:37 INFO - Return code: 0 21:28:37 INFO - grabbing minidump binary from tooltool 21:28:37 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')]} 21:28:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5ace00>, '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 0x10d415db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:28:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:28:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 21:28:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:28:37 INFO - Return code: 0 21:28:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:28:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:28:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:28:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:28:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:28:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:28:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:28:39 INFO - Return code: 0 21:28:39 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/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0377', '--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 21:28:39 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/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0377 --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 21:28:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9gE2iRMA/Render', 21:28:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:28:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:28:39 INFO - 'HOME': '/Users/cltbld', 21:28:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:28:39 INFO - 'LOGNAME': 'cltbld', 21:28:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:28:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:39 INFO - 'MOZ_NO_REMOTE': '1', 21:28:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:28:39 INFO - 'NO_EM_RESTART': '1', 21:28:39 INFO - 'PAGER': '/bin/cat', 21:28:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:39 INFO - 'PWD': '/builds/slave/test', 21:28:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:28:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:28:39 INFO - 'SHELL': '/bin/bash', 21:28:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJINXDJeZg/Listeners', 21:28:39 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 21:28:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:28:39 INFO - 'USER': 'cltbld', 21:28:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:28:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:39 INFO - 'XPC_FLAGS': '0x0', 21:28:39 INFO - 'XPC_SERVICE_NAME': '0', 21:28:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:28:39 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/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0377', '--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 21:28:39 INFO - mozversion application_buildid: 20160623195857 21:28:39 INFO - mozversion application_changeset: a6696f118cb2a90fb4071fc428e37cf826256db6 21:28:39 INFO - mozversion application_display_name: Firefox 21:28:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:28:39 INFO - mozversion application_name: Firefox 21:28:39 INFO - mozversion application_remotingname: firefox 21:28:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:28:39 INFO - mozversion application_vendor: Mozilla 21:28:39 INFO - mozversion application_version: 48.0 21:28:39 INFO - mozversion platform_buildid: 20160623195857 21:28:39 INFO - mozversion platform_changeset: a6696f118cb2a90fb4071fc428e37cf826256db6 21:28:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:28:39 INFO - mozversion platform_version: 48.0 21:28:39 INFO - using testdate: 1466742519 21:28:39 INFO - actual date: 1466742519 21:28:39 INFO - starting webserver on 'localhost:54723' 21:28:39 INFO - SUITE-START | Running 2 tests 21:28:39 INFO - TEST-START | dromaeo_css 21:28:39 INFO - operating with platform_type : mac_ 21:28:39 INFO - Initialising browser for dromaeo_css test... 21:28:39 INFO - TEST-INFO | started process 2219 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmpnLqAl6/profile http://localhost:54723/getInfo.html) 21:28:41 INFO - PROCESS | 2219 | __metrics Screen width/height:1600/1200 21:28:41 INFO - PROCESS | 2219 | colorDepth:24 21:28:41 INFO - PROCESS | 2219 | Browser inner width/height: 1024/689 21:28:41 INFO - PROCESS | 2219 | __metrics 21:28:41 ERROR - PROCESS | 2219 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 21:28:42 INFO - TEST-INFO | 2219: exit 0 21:28:42 INFO - Browser initialized. 21:28:42 INFO - Running cycle 1/1 for dromaeo_css test... 21:28:42 INFO - TEST-INFO | started process 2223 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmpnLqAl6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:28:54 INFO - PROCESS | 2223 | RSS: Main: 170708992 21:28:54 INFO - PROCESS | 2223 | 21:29:43 INFO - PROCESS | 2223 | 1466742583948 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] 21:31:54 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54723/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54723/tests/dromaeo/cssquery-ext.html) 21:31:55 INFO - PROCESS | 2223 | RSS: Main: 181768192 21:31:55 INFO - PROCESS | 2223 | 21:34:57 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54723/tests/dromaeo/cssquery-ext.html (next: http://localhost:54723/tests/dromaeo/cssquery-jquery.html) 21:34:57 INFO - PROCESS | 2223 | RSS: Main: 192524288 21:34:57 INFO - PROCESS | 2223 | 21:37:59 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54723/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54723/tests/dromaeo/cssquery-mootools.html) 21:38:00 INFO - PROCESS | 2223 | RSS: Main: 194588672 21:38:00 INFO - PROCESS | 2223 | 21:41:01 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54723/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54723/tests/dromaeo/cssquery-prototype.html) 21:41:02 INFO - PROCESS | 2223 | RSS: Main: 198455296 21:41:02 INFO - PROCESS | 2223 | 21:44:05 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54723/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54723/tests/dromaeo/cssquery-yui.html) 21:44:05 INFO - PROCESS | 2223 | RSS: Main: 197615616 21:44:05 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54723/tests/dromaeo/cssquery-yui.html (next: http://localhost:54723/tests/dromaeo/cssquery-dojo.html) 21:47:08 INFO - PROCESS | 2223 | RSS: Main: 202960896 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | __start_tp_report 21:47:08 INFO - PROCESS | 2223 | _x_x_mozilla_page_load 21:47:08 INFO - PROCESS | 2223 | _x_x_mozilla_page_load_details 21:47:08 INFO - PROCESS | 2223 | |i|pagename|runs| 21:47:08 INFO - PROCESS | 2223 | |0;dojo.html;3640.5995340512545;3656.8537258509677;3676.209614932789;3714.0529165062912;3720.1257704439454;5895.292564892214;5974.850628734283;6016.639001659898;6018.819435416934;6044.274687037561;5939.346671866102;6009.248843894513;6019.75920963162;6045.123457098718;6046.697665116738;5316.415194328621;5347.893042139155;5372.650777699437;5422.213779002042;5456;5312.017276803785;5342.732863356836;5374.811881584145;5431.83704488864;5450.91823622646;5383.4616538346245;5390.083685773408;5445.754941027663;5450.5367043801325;5452.809151679692;5374.301340825707;5404.297441332621;5419.945800541984;5428.185772134172;5466.808661696821;4382.233109205877;5243.9213411798855;5275.393329767087;5314.096603577401;5366.060939335601;4878.853634390974;5761.222234998263;5785.247917770663;5802.477777000048;5811.593188476808;6290.961991271481;6293.590916590407;6359.173307470046;6363.490920726331;6386.712597933104;6224.688765561704;6265.248170219557;6276.403741644582;6302.369763023707;6302.779402720883;6119.2350956130485;6137.570370074097;6138.355472675349;6140.8771824563255;6164.537659675542;6536.575122617015;6548.8035358939305;6558.147440832661;6563.245226798884;6567.244766851778;6250.937490625036;6266.373362663789;6315.873682526324;6320.747170113144;6327.746890124436;6352.523560732963;6353.650549219838;6362.650054247061;6362.981922892408;6395.232572091286;6239.656818875005;6248.968755156286;6260.311365749764;6306.990881459036;6321.494280457552;6202.0696895465135;6231.626102433868;6297.244330680348;6299.338569450233;6317.210348706411;5052.823151189691;5093.981203759263;5112.079825631347;5119.206522988943;5131.999260144236;4510.436195475566;4524.819007239739;4598.908021839545;4603.424571928509;4618.745968971739;6079.331273559905;6085.908711369333;6201.441870231701;6208.50331973441;6222.937770622327;6323.114763933053;6366.7771627993725;6379.681015949184;6380.457661098859;6393.712282947278;6098.390160983955;6135.877282454415;6173.722182501798;6218.751249950042;6218.906716399258;5518.44815518453;5560.610757247035;5631.8592035199445;5656.943430565724;5664.490195882308;6614.0740296358545;6619.569727967667;6643.7674681385915;6646.800595982225;6672.266050734415;6372.840678983062;6393.808185754435;6399.072134540373;6401.711922963571;6409.7115629795835;5513.61404701667;6021.759129634941;6538.738450461929;6677.2655007948115;6698.229703583956;6253.1245625613365;6253.906191407042;6306.180196574416;6317.336679648754;6339.587926784929;6248.781292654735;6381.553291269566;6394.904076438764;6416.679166041773;6444.967775161094;6128.601640893327;6165.383461653978;6224.190855188796;6274.278457977391;6282.623042617458;6259.96870015647;6291.182146320949;6295.244570651551;6299.748010079546;6317.5893566918;6032.969835150796;6111.205543279345;6199.008158694587;6218.626882387071;6252.187215661935;6264.498840092872;6268.529860260407;6304.306526282197;6326.430621243926;6342.429181373698;6219.0982307566055;6276.372362763687;6281.403266689657;6285.717142728679;6311.652859092794 21:47:08 INFO - PROCESS | 2223 | |1;ext.html;541.1175051048821;547.7288742072674;556.1685280505644;599.9640021598707;624.7750809708505;38687.61312386875;39738;39824.60175398245;39875.800620996924;40201.79899100504;40662;40698.59301406985;40725;41258.58741412585;41346.7932660337;9651;9684.176844968151;9689.660861869836;9709.320347575673;9889.653862114827;9894.851577226347;10080.042395972407;10089.344192627492;10097.798044039115;10124.03821636947;9913.801723965516;10040.799184016316;10100.747481312952;10102.040306170938;10109.090181883628;9848.852267215962;9895.406275623485;9963.800723985516;10058.094771470565;10090.243231757648;9748.31761776676;9781.90218097817;9782.804343913152;9881.25890558211;9930.304878658424;40266.798666006776;40370;40712;40716.79641601803;40720.98197545055;3350.9832450837835;3430.5711786026745;3559.804210768407;3637.6907962823193;3898.1034362096593;29004.12987610375;29817.552736708967;29927.40145197084;30143;30148.69851301481;2287.622542280522;2826.858657067138;2842.6588809342807;2845.4166895786316;2907.5202591572574;485478;489887;490750;491982;492237;51946.48053519492;55766.048188313216;65014;66282;66331;68746.65626671835;76677;76950;77071;77087.61456192796;19044.61910761777;19282;19311.903440482987;19518.219271229274;19522;14866.776998345034;14933.17867517297;15016.24918754058;15048.699026019418;15057.623559411102;20965.161393544393;21219.78780212178;21245.68131478029;21283.574328513343;21345.46636334073;8707.782305442415;8744.562771861381;8749.825003499895;8752.21230089295;8866.556672166365;998.4773220838134;1037.7405648587853;1067.9199060070525;1156.0173852225541;1234.2841152131741;31858.681413186034;32079;32624.510632340534;33273;34271.65728342735;1033.7932413517176;1065.8401239813934;1169.824326551827;1220.926744395339;1225.2770865189582;15301.311440985182;15489.07065576069;15564.688706225812;15682.76475852863;15733.370665173265;660982.3901761017;663887;664606;669336;672834;61332.69333653392;62015;62248.06627900585;62314.376856231764;62491.375086248554;1563.3277690593154;1579.8104227492774;1582.4303250829691;1591.124881315272;1663.3679201903442;30312;30675.693243067282;30799.846000769852;31105.222369440944;31345.686543134278;55547.444525555846;56069.87860242854;56461;56636;56656.433435665116;612834;615618;618869;619012;620633;26885;27374;27804.19272747265;27837.02570410026;28005.43989120247;45823;46079;47067;47363.763181185255;47577.33479328207;38705.6129438702;38960;39266;39345;39650.41398344034;38473.230535389695;38514.80742596269;38845.805770972096;38919;39628.60371396249;33258.50112248366;38102.666406675635;38287.23425531418;38306.80846595749;38715.22569548649;31722;33463.16342091467;33497.330053398306;33666;33741.49387759136;25519.10683126156;25643.35891602725;25655.230343089737;25785.849715597094;26061.957521698918 21:47:08 INFO - PROCESS | 2223 | |2;jquery.html;415.1033767063144;416.8645190313148;419.2997693851268;421.6563500746891;428.3638796387366;21208.045637946292;21264.89367553162;21929.23247686331;23149;23221.76778232217;22420.55158896821;22447.87760611971;22564.43588910274;22685.886570567167;23117;5964.821055368335;6233.158523599324;6291;6294.0873573331955;6301.306856245809;4554.84058057968;6132.417420345082;6261.46777523911;6416.45460135889;6918.411934985532;6741.8314542136595;6748.797536073901;6748.932510674879;6823.55646882951;6854.2117656469645;6552.3775241352;6700.095487109251;6721.193456785169;6722.4285935695525;6837.555558888656;5933.376995415506;6102.633841969496;6113.969430152865;6132.294786099566;7239.276072392771;29700;29956.400871982438;30024.099277021724;30050;30480.69519304823;1988.443235893952;2048.4366799130207;2338.0414030247516;2366.9403099966644;2515.434027343851;15793.842061579351;16470.941132358454;16543.172841357886;17196.484105476855;17526.29894804219;1904.9998750656016;1922.1062206074103;1938.6995015772577;1974.2300502803919;1997.0514005847251;93401;130143.69856301504;136442;139694;140296;18963.146658400405;22340.776592234193;22860.7713922862;23141.53716925652;23268.418289542897;36741;36908;37456;37716;38129.80935095362;10311.742206444898;11574.668913074936;12095.092868034933;12207.145499815106;12226.327551984548;10080.798384032278;10110.646127385506;10132.594696212216;10169.186465082776;10235.130013948748;9356.532173391302;10714.624988125533;10797.62208322705;10904.50929708165;10905.727356815984;1837.3477415517566;2078.002558771798;2111.9472013199793;2154.439845640145;4077.1845630873945;811.9309858661961;966.2270183852889;991.9305648604673;1050.0811789684026;1057.3814318623538;21560.892195539236;22668.206612768474;22689.746738142774;22760.544789104126;22775.430614234447;1020.3367810922811;1022.6420752736632;1047.1988928469727;1064.5741703318579;1067.1622776120707;11652.708682283012;12010.879891200977;12107.273563586214;12143.574974875837;12232.938835305766;677471.4505709858;682371;692195;702857;713534;139982;140754;148758;150102;150113.24943375212;1103.5034234594464;1112.5438570186184;1119.3060302612269;1124.3872089710962;1129.3167633581565;19417.93201373938;19923.900380498006;20086;20100.69848952296;20162.19351225993;57372.42627573671;58250.252492425294;58285;59776.70111649588;60153.69923150356;71073;77127.61436192783;88082.11917880995;89856.10143898477;90691.54654226686;8231.46495477792;8365.707200248205;8401.537915414467;8533.018702849251;8651.875256216652;9352.298577606844;12535.561255356019;12883.935580322039;13172.934135329262;13309.733805323645;11095.667129986114;11392;11547.307161570332;11671.241369310968;11693.064554835762;11127.721806954567;11290.43547822622;11292.943535282271;11547.4803633835;11598;9264.351495395258;11549.960503554721;11732;11883.405829708307;11941.82087268709;8317.792055198668;8509;8519.06290308078;8545.444546104483;8566.742997710078;9226.630934762803;11204.943975280072;11427.714307142389;11658.475368608264;11717.88282117168 21:47:08 INFO - PROCESS | 2223 | |3;mootools.html;1644.6381796004878;1676.6395225026617;1966.2331690640654;1992.1135094882766;2011.7887621799712;7110.1112360954885;7758.379329653629;7963.8805417918775;8327.333813294937;9434.905650943498;6495.935040649592;9077.500737459437;9139.543022848848;9200;9231.076892310766;2197.8351623628214;2473.0107956817237;2534.8352357096774;2551.0561092395837;2565.7947364210913;2490.2155820916446;2561.4108754986273;2561.820672552922;2566.5893457046877;2569.3062873024273;2137.572485502898;2488.104282458323;2527.279725278296;2552.208815267273;2594.9351266218305;2519.2316343515313;2523.129520315488;2547.7197508274076;2580.729023452548;2581.277242372139;2529.962050569243;2572;2574.9485010299877;2587.4178309880313;2597.6103584462294;9312.813743725155;9343;9383.014783447776;9383.624655013791;9387.014363491871;5188.45521220274;5698.458646428583;5711.371749107595;5720;5892.970535147339;3634.3821550336506;3712.7215458840697;3759.8684046058256;3770.5671844699114;3777.6788972937334;893.8569828827422;1071.2394200117926;1071.7588542577935;1075.7364445710773;1096.4298564746366;12179.269243845398;12222.144449888423;12681.36593170347;12686.365681715877;12699.682507937283;4507.233770259064;4824.517548245218;5055.595552355802;5081.856582269033;5178.6374953752875;10495.212859035602;10504.527296271686;10771.569137234423;10777.568897244022;10785.029347358775;5675.2054712340305;5713.371529131792;5713.8285851424525;5859.062549991981;5869.442402971797;4684.320773487893;5091.821786237464;5136.1525348317855;5176.3270774799785;5226.111561034633;4189.958100419018;4266.722663026893;4349.9130017399475;4353.36876152956;4364.825406983685;1384.819973403451;1406.9507567235098;1408.3169662713576;1418.9432422702976;1424.302091974925;953.3945944325403;984.3601658921758;1004.076249850125;1009.258195226508;1022.257051726804;6116.877662446726;6152.753889844356;6235.937640623626;6254.982461734759;6267.96866015667;442.6388854273123;458.60789025382815;483.9201531747222;497.9203327467588;500.05489624609515;4084.1219137885496;4090.2637525245145;4105.774182419996;4109.589041095866;4117.279476091732;10848.403337816495;11066.336019838835;11176.552937882556;11286.040686541572;11331.943340283246;10912.23614347004;10937.83593246102;11025.062869656009;11125.109991200685;11133.165012623924;8578.313734901318;8625.568721563897;8664.523451210118;8667.393282470293;8794.076621954731;5840.561957853093;5956.791512297049;6071.605345652519;6077.544184186116;6185.319614842454;9893.109620134222;9947.801043979225;9957.004299569984;9991.500424978576;10064.295499314978;10099.899001009895;10356.482175891024;10482;10507.15942724565;10645.733856653645;7617.923820761722;7902.209779022051;7912.802179945547;7921.1682773307975;7968.0438347398685;8677.913220867711;8684.522351270742;8722.389432739647;8733.868991964999;8764.474131552128;7593.202713715202;7750.147483776667;7751.844963100819;7752.8837067445165;7837.6081195941115;7573.9242607573215;7651.961740191264;7725.729599463992;7782.066152061789;7811.414143939113;5387.191921211849;6335.904961425675;6666.933290673469;7253.927460725537;7436.293552112541;4996.775145118406;5038.319826823332;5116.820911268088;5212.582993360446;5213.452587478412;4512.864614061584;4628.83799067031;5069.112905241494;5118.897622047463;5272.156454967187 21:47:08 INFO - PROCESS | 2223 | |4;prototype.html;323.87937735435395;332.1679193619985;337.76356550414715;343.0600155573728;347.75135777918786;6686.364795344447;6757.054012438262;6845.349691779279;6857.108575885134;6867.58794472332;4939.901201975958;6669.066330713704;6774.9322506774915;6860.897086543706;6875.44996400288;5094.566961808251;5102.464241254652;5112.003159383912;5113.156329205676;5126.41046279677;4167.395727619493;5096.719680417576;5108.080545501808;5118.334616499868;5128.461511541291;5053.823116190934;5057.772400241998;5087;5089.51649593288;5091.439941606421;4672.859814205545;4881.316615673809;5068.1890897456415;5073.949260507403;5086;4111.848682368942;4138.13099249158;4141.689373297015;4152.045029643169;4153.771542565158;6478.060681201199;6496.870062598721;6538.77114301002;6540.869182616369;6602.207735071774;5766.221560089376;5828.417158284137;5866.266716660418;5872.559558033165;5905.202797622309;5938.8812223755285;6948.27043160471;6989.33601307875;7177.4258059355125;7186.02988596538;4561.657875659339;4599.678022538424;4682.906341873144;4736.881577960544;4759.595434388081;7726.227377262285;7778.494397864178;7831.334336581397;7842.8823567647105;7848;4791.832285869978;4809.735464549483;4810.735409552508;4813.638977076734;4821.831235906727;4889.4377146627885;4896.143174944371;4899.706017638881;4910.238912968424;4923.187674033816;5489.725513724298;5583.162525621108;5795.565332599987;5892.616979896293;5901.878643057806;7191.532550384313;7304.050473438419;7389.926100739031;7391.113066431955;7393.334599885929;1668.4994501649458;1688.5525335786026;1694.9745753813702;1696.872734544914;1699.9830001699825;980.3970558106713;994.433173091331;1058.9939557420482;1080.400377790334;1085.1698450685233;6943.9652801735665;6962.094927659372;6968.0941477607585;6991.825204369931;6996.930030699729;7575.734849280248;7585.1656317805;7599.050118735158;7606.771796846104;7634;6933.064036355128;6947.305269473113;6952.895706564405;6990.196127445307;6995.26549712283;3885.6502914737807;3888.319544079775;3997.560268370477;4027.154297597479;4048.514178298623;7832.882506762403;8009.479383840032;8047.195280471906;8060.556669383123;8110.026796784424;7900.920990790019;7911.248431399008;7916.881246781303;7936.523808571505;7947.3642108631175;6385.776497822554;8055.234752698601;8094.554799485967;8106.513609183467;8122.228388303101;7295.4528410368375;7500.774976750707;7614.581198034161;7685;7737.806554836174;7529.435292353205;7561.6597253122645;7575.469717119749;7587.886181707168;7588.317051465395;6471.223453185459;8077;8105.837883242184;8123.309518690978;8136.87794683092;3926.1362500249897;4757.072370887644;4830.686005409637;4831.879203019953;4839.661223714306;7316.634168291671;7571.091469023752;7576.24237576238;7577.1665116836;7678.577678227751;7222.313880181375;7333.6333183341685;7393.704251829867;7404.222556631476;7405.333519983227;7365.963170184329;7408.185099638928;7412.369948554433;7431.88521721746;7469.439792015729;5673.773049078156;7034.894844511051;7194.892076618959;7212.783616491513;7244;7328.926710732824;7350.816229594303;7362.042934418491;7371.336579707852;7391.525982788081;6457;6594.043863639649;7194.136703595393;7217.494775365674;7233.421326293778 21:47:08 INFO - PROCESS | 2223 | |5;yui.html;724.6123324021648;782.077148964222;786.5045021636369;791.9326857217136;793.1711361627099;15696;15723.213839308046;15956.96279741816;15984.440544580943;16040.037597744144;14803.92598037011;14849.77725334118;14871.851281487208;14923;14943.551693449213;790.7548659915444;977.4575110813496;994.2592768387561;996.8853581838109;998.0518507417946;911.5487833522383;931.1107891963159;932.342698397628;934.22926085979;979.6913972098802;991.4794732765283;991.6033586565395;1000.5097502223895;1041.5209003858233;1041.7916416716648;986.3588667366233;993.2004736187366;993.7366597851575;1031.9742006449862;1035.4563853976647;746.4588173574169;746.7573038762424;747.2116916652928;747.4805010517692;784.2040329066002;14905.925470372687;15373.308201130976;15396;15448.382064717398;15455.459058932996;13278.269695166762;13285.667858303523;13354.599362019155;13429.261390623511;13486.258255795927;9983.500824958795;10290.485475726258;10393.06462418386;10457.006584374472;10639;3548.201654627714;3609.657082577151;3630.4009838376637;3633.9273214535824;3863.787491687956;87436.5628171855;90077;91298;91595.6260656084;92888.53555732245;9828.361156524803;9851.605935762633;9949.403035817873;10091.798164036678;10117.443540605338;22461.32616021522;22596.435089122904;22934.96792644335;23353.832308384513;23433;7804.297613214838;7903;7927.801804954922;7984.481008734414;8088.595570221465;4249.150169966019;4261.531231564525;4265.296226122717;4274.401583778274;4303.827846886152;3694.556653201633;3806.467094606757;3864.8598663393805;3866.5940076292145;3871.3031654302495;708.0724251230904;711.8042538302009;724.2974314914525;730.3682314797662;731.7841236835146;507.77150282373077;541.0288532084917;559.700560200299;567.5658121537027;586.7535635033296;8108.905297784723;8191.713290034821;8225.341972642174;8293.95853020743;8419.915800841913;421.7848897062459;494.08840688929587;537.8010136249612;582.9213056237438;588.5144755576666;3711.2391959648207;3824.9426258606145;3850.595687452777;3865.903352416212;4056.8377264909072;78271;94834.52582737042;95054.5247273773;101814;104365.47817260865;80602;83858.58070709607;86307.56846215729;86937.26125477455;89274.10725892788;74585;74996.62501687456;76730.46539069299;77181.99999999888;77732.61133694409;8681.782955426164;8765.07966663491;8817.823643526965;8861.202491775772;8954.686585969459;41221;48051.75974120247;50110.24834627411;50441.74779126081;52706.73646631742;68849.3115068863;68883.65558172177;70051;70261;73076;11083.612073577386;11132.777344453227;11165.050970667584;11186.664400067686;11539.4807233673;9980.800383992424;12001.459934302802;12200.450979705756;12358.567450139202;12611;11697.067789373383;11838.171328006956;12014.399280036137;12101.273923564615;12105.515779368727;11742.765144697229;11824.172307938707;12081;12119.454624542093;12269.754604907674;11686.18196726221;11731.296122232694;11750.059995200534;11879.46542405611;11966.922976932117;9841.655542055994;10045;10087.546060427152;10366.637167699095;10400.895991039994;9832.427921016762;9971.551280192554;9974.251931105342;10004.699859004242;10091.44497052669 21:47:08 INFO - PROCESS | 2223 | __end_tp_report 21:47:08 INFO - PROCESS | 2223 | __start_cc_report 21:47:08 INFO - PROCESS | 2223 | _x_x_mozilla_cycle_collect,395 21:47:08 INFO - PROCESS | 2223 | __end_cc_report 21:47:08 INFO - PROCESS | 2223 | __startTimestamp1466743628288__endTimestamp 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | ------- Summary: start ------- 21:47:08 INFO - PROCESS | 2223 | Number of tests: 6 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | [#0] dojo.html Cycles:165 Average:5931.86 Median:6218.91 stddev:639.20 (10.3%) stddev-sans-first:615.37 21:47:08 INFO - PROCESS | 2223 | Values: 3640.6 3656.9 3676.2 3714.1 3720.1 5895.3 5974.9 6016.6 6018.8 6044.3 5939.3 6009.2 6019.8 6045.1 6046.7 5316.4 5347.9 5372.7 5422.2 5456.0 5312.0 5342.7 5374.8 5431.8 5450.9 5383.5 5390.1 5445.8 5450.5 5452.8 5374.3 5404.3 5419.9 5428.2 5466.8 4382.2 5243.9 5275.4 5314.1 5366.1 4878.9 5761.2 5785.2 5802.5 5811.6 6291.0 6293.6 6359.2 6363.5 6386.7 6224.7 6265.2 6276.4 6302.4 6302.8 6119.2 6137.6 6138.4 6140.9 6164.5 6536.6 6548.8 6558.1 6563.2 6567.2 6250.9 6266.4 6315.9 6320.7 6327.7 6352.5 6353.7 6362.7 6363.0 6395.2 6239.7 6249.0 6260.3 6307.0 6321.5 6202.1 6231.6 6297.2 6299.3 6317.2 5052.8 5094.0 5112.1 5119.2 5132.0 4510.4 4524.8 4598.9 4603.4 4618.7 6079.3 6085.9 6201.4 6208.5 6222.9 6323.1 6366.8 6379.7 6380.5 6393.7 6098.4 6135.9 6173.7 6218.8 6218.9 5518.4 5560.6 5631.9 5656.9 5664.5 6614.1 6619.6 6643.8 6646.8 6672.3 6372.8 6393.8 6399.1 6401.7 6409.7 5513.6 6021.8 6538.7 6677.3 6698.2 6253.1 6253.9 6306.2 6317.3 6339.6 6248.8 6381.6 6394.9 6416.7 6445.0 6128.6 6165.4 6224.2 6274.3 6282.6 6260.0 6291.2 6295.2 6299.7 6317.6 6033.0 6111.2 6199.0 6218.6 6252.2 6264.5 6268.5 6304.3 6326.4 6342.4 6219.1 6276.4 6281.4 6285.7 6311.7 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | [#1] ext.html Cycles:180 Average:73113.27 Median:38710.42 stddev:159280.39 (411.5%) stddev-sans-first:159634.02 21:47:08 INFO - PROCESS | 2223 | Values: 541.1 547.7 556.2 600.0 624.8 38687.6 39738.0 39824.6 39875.8 40201.8 40662.0 40698.6 40725.0 41258.6 41346.8 9651.0 9684.2 9689.7 9709.3 9889.7 9894.9 10080.0 10089.3 10097.8 10124.0 9913.8 10040.8 10100.7 10102.0 10109.1 9848.9 9895.4 9963.8 10058.1 10090.2 9748.3 9781.9 9782.8 9881.3 9930.3 40266.8 40370.0 40712.0 40716.8 40721.0 3351.0 3430.6 3559.8 3637.7 3898.1 29004.1 29817.6 29927.4 30143.0 30148.7 2287.6 2826.9 2842.7 2845.4 2907.5 485478.0 489887.0 490750.0 491982.0 492237.0 51946.5 55766.0 65014.0 66282.0 66331.0 68746.7 76677.0 76950.0 77071.0 77087.6 19044.6 19282.0 19311.9 19518.2 19522.0 14866.8 14933.2 15016.2 15048.7 15057.6 20965.2 21219.8 21245.7 21283.6 21345.5 8707.8 8744.6 8749.8 8752.2 8866.6 998.5 1037.7 1067.9 1156.0 1234.3 31858.7 32079.0 32624.5 33273.0 34271.7 1033.8 1065.8 1169.8 1220.9 1225.3 15301.3 15489.1 15564.7 15682.8 15733.4 660982.4 663887.0 664606.0 669336.0 672834.0 61332.7 62015.0 62248.1 62314.4 62491.4 1563.3 1579.8 1582.4 1591.1 1663.4 30312.0 30675.7 30799.8 31105.2 31345.7 55547.4 56069.9 56461.0 56636.0 56656.4 612834.0 615618.0 618869.0 619012.0 620633.0 26885.0 27374.0 27804.2 27837.0 28005.4 45823.0 46079.0 47067.0 47363.8 47577.3 38705.6 38960.0 39266.0 39345.0 39650.4 38473.2 38514.8 38845.8 38919.0 39628.6 33258.5 38102.7 38287.2 38306.8 38715.2 31722.0 33463.2 33497.3 33666.0 33741.5 25519.1 25643.4 25655.2 25785.8 26062.0 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | [#2] jquery.html Cycles:180 Average:40673.98 Median:22506.16 stddev:115406.49 (512.8%) stddev-sans-first:115690.65 21:47:08 INFO - PROCESS | 2223 | Values: 415.1 416.9 419.3 421.7 428.4 21208.0 21264.9 21929.2 23149.0 23221.8 22420.6 22447.9 22564.4 22685.9 23117.0 5964.8 6233.2 6291.0 6294.1 6301.3 4554.8 6132.4 6261.5 6416.5 6918.4 6741.8 6748.8 6748.9 6823.6 6854.2 6552.4 6700.1 6721.2 6722.4 6837.6 5933.4 6102.6 6114.0 6132.3 7239.3 29700.0 29956.4 30024.1 30050.0 30480.7 1988.4 2048.4 2338.0 2366.9 2515.4 15793.8 16470.9 16543.2 17196.5 17526.3 1905.0 1922.1 1938.7 1974.2 1997.1 93401.0 130143.7 136442.0 139694.0 140296.0 18963.1 22340.8 22860.8 23141.5 23268.4 36741.0 36908.0 37456.0 37716.0 38129.8 10311.7 11574.7 12095.1 12207.1 12226.3 10080.8 10110.6 10132.6 10169.2 10235.1 9356.5 10714.6 10797.6 10904.5 10905.7 1837.3 2078.0 2111.9 2154.4 4077.2 811.9 966.2 991.9 1050.1 1057.4 21560.9 22668.2 22689.7 22760.5 22775.4 1020.3 1022.6 1047.2 1064.6 1067.2 11652.7 12010.9 12107.3 12143.6 12232.9 677471.5 682371.0 692195.0 702857.0 713534.0 139982.0 140754.0 148758.0 150102.0 150113.2 1103.5 1112.5 1119.3 1124.4 1129.3 19417.9 19923.9 20086.0 20100.7 20162.2 57372.4 58250.3 58285.0 59776.7 60153.7 71073.0 77127.6 88082.1 89856.1 90691.5 8231.5 8365.7 8401.5 8533.0 8651.9 9352.3 12535.6 12883.9 13172.9 13309.7 11095.7 11392.0 11547.3 11671.2 11693.1 11127.7 11290.4 11292.9 11547.5 11598.0 9264.4 11550.0 11732.0 11883.4 11941.8 8317.8 8509.0 8519.1 8545.4 8566.7 9226.6 11204.9 11427.7 11658.5 11717.9 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | [#3] mootools.html Cycles:180 Average:5925.57 Median:490.92 stddev:3323.03 (676.9%) stddev-sans-first:3316.79 21:47:08 INFO - PROCESS | 2223 | Values: 1644.6 1676.6 1966.2 1992.1 2011.8 7110.1 7758.4 7963.9 8327.3 9434.9 6495.9 9077.5 9139.5 9200.0 9231.1 2197.8 2473.0 2534.8 2551.1 2565.8 2490.2 2561.4 2561.8 2566.6 2569.3 2137.6 2488.1 2527.3 2552.2 2594.9 2519.2 2523.1 2547.7 2580.7 2581.3 2530.0 2572.0 2574.9 2587.4 2597.6 9312.8 9343.0 9383.0 9383.6 9387.0 5188.5 5698.5 5711.4 5720.0 5893.0 3634.4 3712.7 3759.9 3770.6 3777.7 893.9 1071.2 1071.8 1075.7 1096.4 12179.3 12222.1 12681.4 12686.4 12699.7 4507.2 4824.5 5055.6 5081.9 5178.6 10495.2 10504.5 10771.6 10777.6 10785.0 5675.2 5713.4 5713.8 5859.1 5869.4 4684.3 5091.8 5136.2 5176.3 5226.1 4190.0 4266.7 4349.9 4353.4 4364.8 1384.8 1407.0 1408.3 1418.9 1424.3 953.4 984.4 1004.1 1009.3 1022.3 6116.9 6152.8 6235.9 6255.0 6268.0 442.6 458.6 483.9 497.9 500.1 4084.1 4090.3 4105.8 4109.6 4117.3 10848.4 11066.3 11176.6 11286.0 11331.9 10912.2 10937.8 11025.1 11125.1 11133.2 8578.3 8625.6 8664.5 8667.4 8794.1 5840.6 5956.8 6071.6 6077.5 6185.3 9893.1 9947.8 9957.0 9991.5 10064.3 10099.9 10356.5 10482.0 10507.2 10645.7 7617.9 7902.2 7912.8 7921.2 7968.0 8677.9 8684.5 8722.4 8733.9 8764.5 7593.2 7750.1 7751.8 7752.9 7837.6 7573.9 7652.0 7725.7 7782.1 7811.4 5387.2 6335.9 6666.9 7253.9 7436.3 4996.8 5038.3 5116.8 5212.6 5213.5 4512.9 4628.8 5069.1 5118.9 5272.2 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | [#4] prototype.html Cycles:180 Average:5978.15 Median:6859.00 stddev:1945.82 (28.4%) stddev-sans-first:1904.43 21:47:08 INFO - PROCESS | 2223 | Values: 323.9 332.2 337.8 343.1 347.8 6686.4 6757.1 6845.3 6857.1 6867.6 4939.9 6669.1 6774.9 6860.9 6875.4 5094.6 5102.5 5112.0 5113.2 5126.4 4167.4 5096.7 5108.1 5118.3 5128.5 5053.8 5057.8 5087.0 5089.5 5091.4 4672.9 4881.3 5068.2 5073.9 5086.0 4111.8 4138.1 4141.7 4152.0 4153.8 6478.1 6496.9 6538.8 6540.9 6602.2 5766.2 5828.4 5866.3 5872.6 5905.2 5938.9 6948.3 6989.3 7177.4 7186.0 4561.7 4599.7 4682.9 4736.9 4759.6 7726.2 7778.5 7831.3 7842.9 7848.0 4791.8 4809.7 4810.7 4813.6 4821.8 4889.4 4896.1 4899.7 4910.2 4923.2 5489.7 5583.2 5795.6 5892.6 5901.9 7191.5 7304.1 7389.9 7391.1 7393.3 1668.5 1688.6 1695.0 1696.9 1700.0 980.4 994.4 1059.0 1080.4 1085.2 6944.0 6962.1 6968.1 6991.8 6996.9 7575.7 7585.2 7599.1 7606.8 7634.0 6933.1 6947.3 6952.9 6990.2 6995.3 3885.7 3888.3 3997.6 4027.2 4048.5 7832.9 8009.5 8047.2 8060.6 8110.0 7900.9 7911.2 7916.9 7936.5 7947.4 6385.8 8055.2 8094.6 8106.5 8122.2 7295.5 7500.8 7614.6 7685.0 7737.8 7529.4 7561.7 7575.5 7587.9 7588.3 6471.2 8077.0 8105.8 8123.3 8136.9 3926.1 4757.1 4830.7 4831.9 4839.7 7316.6 7571.1 7576.2 7577.2 7678.6 7222.3 7333.6 7393.7 7404.2 7405.3 7366.0 7408.2 7412.4 7431.9 7469.4 5673.8 7034.9 7194.9 7212.8 7244.0 7328.9 7350.8 7362.0 7371.3 7391.5 6457.0 6594.0 7194.1 7217.5 7233.4 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - PROCESS | 2223 | [#5] yui.html Cycles:180 Average:19316.92 Median:24272.92 stddev:27504.51 (113.3%) stddev-sans-first:27546.24 21:47:08 INFO - PROCESS | 2223 | Values: 724.6 782.1 786.5 791.9 793.2 15696.0 15723.2 15957.0 15984.4 16040.0 14803.9 14849.8 14871.9 14923.0 14943.6 790.8 977.5 994.3 996.9 998.1 911.5 931.1 932.3 934.2 979.7 991.5 991.6 1000.5 1041.5 1041.8 986.4 993.2 993.7 1032.0 1035.5 746.5 746.8 747.2 747.5 784.2 14905.9 15373.3 15396.0 15448.4 15455.5 13278.3 13285.7 13354.6 13429.3 13486.3 9983.5 10290.5 10393.1 10457.0 10639.0 3548.2 3609.7 3630.4 3633.9 3863.8 87436.6 90077.0 91298.0 91595.6 92888.5 9828.4 9851.6 9949.4 10091.8 10117.4 22461.3 22596.4 22935.0 23353.8 23433.0 7804.3 7903.0 7927.8 7984.5 8088.6 4249.2 4261.5 4265.3 4274.4 4303.8 3694.6 3806.5 3864.9 3866.6 3871.3 708.1 711.8 724.3 730.4 731.8 507.8 541.0 559.7 567.6 586.8 8108.9 8191.7 8225.3 8294.0 8419.9 421.8 494.1 537.8 582.9 588.5 3711.2 3824.9 3850.6 3865.9 4056.8 78271.0 94834.5 95054.5 101814.0 104365.5 80602.0 83858.6 86307.6 86937.3 89274.1 74585.0 74996.6 76730.5 77182.0 77732.6 8681.8 8765.1 8817.8 8861.2 8954.7 41221.0 48051.8 50110.2 50441.7 52706.7 68849.3 68883.7 70051.0 70261.0 73076.0 11083.6 11132.8 11165.1 11186.7 11539.5 9980.8 12001.5 12200.5 12358.6 12611.0 11697.1 11838.2 12014.4 12101.3 12105.5 11742.8 11824.2 12081.0 12119.5 12269.8 11686.2 11731.3 11750.1 11879.5 11966.9 9841.7 10045.0 10087.5 10366.6 10400.9 9832.4 9971.6 9974.3 10004.7 10091.4 21:47:08 INFO - PROCESS | 2223 | -------- Summary: end -------- 21:47:08 INFO - PROCESS | 2223 | 21:47:08 INFO - TEST-INFO | 2223: exit 0 21:47:08 INFO - TEST-OK | dromaeo_css | took 1109570ms 21:47:08 INFO - TEST-START | kraken 21:47:08 INFO - operating with platform_type : mac_ 21:47:08 INFO - Initialising browser for kraken test... 21:47:08 INFO - TEST-INFO | started process 2230 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmponOJIw/profile http://localhost:54723/getInfo.html) 21:47:11 INFO - PROCESS | 2230 | __metrics Screen width/height:1600/1200 21:47:11 INFO - PROCESS | 2230 | colorDepth:24 21:47:11 INFO - PROCESS | 2230 | Browser inner width/height: 1024/689 21:47:11 INFO - PROCESS | 2230 | __metrics 21:47:11 ERROR - PROCESS | 2230 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 21:47:12 INFO - TEST-INFO | 2230: exit 0 21:47:12 INFO - Browser initialized. 21:47:12 INFO - Running cycle 1/1 for kraken test... 21:47:12 INFO - TEST-INFO | started process 2234 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmponOJIw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:47:24 INFO - PROCESS | 2234 | RSS: Main: 171151360 21:47:24 INFO - PROCESS | 2234 | 21:47:26 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54723/tests/kraken/driver.html?testName=audio-beat-detection) 21:47:27 INFO - PROCESS | 2234 | RSS: Main: 183578624 21:47:27 INFO - PROCESS | 2234 | 21:47:30 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54723/tests/kraken/driver.html?testName=audio-dft) 21:47:31 INFO - PROCESS | 2234 | RSS: Main: 192557056 21:47:31 INFO - PROCESS | 2234 | 21:47:34 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54723/tests/kraken/driver.html?testName=audio-fft) 21:47:35 INFO - PROCESS | 2234 | RSS: Main: 196132864 21:47:35 INFO - PROCESS | 2234 | 21:47:37 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54723/tests/kraken/driver.html?testName=audio-oscillator) 21:47:37 INFO - PROCESS | 2234 | RSS: Main: 196141056 21:47:37 INFO - PROCESS | 2234 | 21:47:39 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54723/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:47:40 INFO - PROCESS | 2234 | RSS: Main: 196407296 21:47:40 INFO - PROCESS | 2234 | 21:47:43 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54723/tests/kraken/driver.html?testName=imaging-darkroom) 21:47:43 INFO - PROCESS | 2234 | RSS: Main: 197099520 21:47:43 INFO - PROCESS | 2234 | 21:47:46 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54723/tests/kraken/driver.html?testName=imaging-desaturate) 21:47:47 INFO - PROCESS | 2234 | RSS: Main: 198479872 21:47:47 INFO - PROCESS | 2234 | 21:47:50 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54723/tests/kraken/driver.html?testName=json-parse-financial) 21:47:50 INFO - PROCESS | 2234 | RSS: Main: 199163904 21:47:50 INFO - PROCESS | 2234 | 21:47:52 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54723/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:47:53 INFO - PROCESS | 2234 | RSS: Main: 197734400 21:47:53 INFO - PROCESS | 2234 | 21:47:54 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:47:55 INFO - PROCESS | 2234 | RSS: Main: 197873664 21:47:55 INFO - PROCESS | 2234 | 21:47:57 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:47:58 INFO - PROCESS | 2234 | RSS: Main: 198766592 21:47:58 INFO - PROCESS | 2234 | 21:48:01 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:48:01 INFO - PROCESS | 2234 | RSS: Main: 198119424 21:48:01 INFO - PROCESS | 2234 | 21:48:04 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:48:05 INFO - PROCESS | 2234 | RSS: Main: 198066176 21:48:05 INFO - PROCESS | 2234 | 21:48:06 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:54723/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54723/tests/kraken/driver.html?testName=ai-astar) 21:48:07 INFO - PROCESS | 2234 | RSS: Main: 199757824 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | __start_tp_report 21:48:07 INFO - PROCESS | 2234 | _x_x_mozilla_page_load 21:48:07 INFO - PROCESS | 2234 | _x_x_mozilla_page_load_details 21:48:07 INFO - PROCESS | 2234 | |i|pagename|runs| 21:48:07 INFO - PROCESS | 2234 | |0;ai-astar;113;120;109;138;178;160;105;115;140;148 21:48:07 INFO - PROCESS | 2234 | |1;audio-beat-detection;157;125;149;169;146;170;154;153;158;149 21:48:07 INFO - PROCESS | 2234 | |2;audio-dft;271;254;198;197;194;195;195;197;196;201 21:48:07 INFO - PROCESS | 2234 | |3;audio-fft;58;88;111;180;56;50;58;84;161;57 21:48:07 INFO - PROCESS | 2234 | |4;audio-oscillator;76;80;134;90;79;68;140;65;82;163 21:48:07 INFO - PROCESS | 2234 | |5;imaging-gaussian-blur;91;90;90;88;91;87;91;89;90;86 21:48:07 INFO - PROCESS | 2234 | |6;imaging-darkroom;120;95;96;112;149;125;94;95;94;96 21:48:07 INFO - PROCESS | 2234 | |7;imaging-desaturate;147;97;90;90;91;89;89;91;90;91 21:48:07 INFO - PROCESS | 2234 | |8;json-parse-financial;59;63;64;64;64;64;65;78;61;59 21:48:07 INFO - PROCESS | 2234 | |9;json-stringify-tinderbox;63;64;63;70;66;93;62;62;83;94 21:48:07 INFO - PROCESS | 2234 | |10;stanford-crypto-aes;79;79;82;81;80;81;80;80;83;82 21:48:07 INFO - PROCESS | 2234 | |11;stanford-crypto-ccm;127;130;130;147;158;123;150;119;168;175 21:48:07 INFO - PROCESS | 2234 | |12;stanford-crypto-pbkdf2;163;164;161;165;166;164;161;166;163;163 21:48:07 INFO - PROCESS | 2234 | |13;stanford-crypto-sha256-iterative;66;68;64;65;71;67;67;69;66;65 21:48:07 INFO - PROCESS | 2234 | __end_tp_report 21:48:07 INFO - PROCESS | 2234 | __start_cc_report 21:48:07 INFO - PROCESS | 2234 | _x_x_mozilla_cycle_collect,1052 21:48:07 INFO - PROCESS | 2234 | __end_cc_report 21:48:07 INFO - PROCESS | 2234 | __startTimestamp1466743687033__endTimestamp 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | ------- Summary: start ------- 21:48:07 INFO - PROCESS | 2234 | Number of tests: 14 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#0] ai-astar Cycles:10 Average:132.60 Median:139.00 stddev:24.28 (17.5%) stddev-sans-first:24.69 21:48:07 INFO - PROCESS | 2234 | Values: 113.0 120.0 109.0 138.0 178.0 160.0 105.0 115.0 140.0 148.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#1] audio-beat-detection Cycles:10 Average:153.00 Median:155.50 stddev:12.70 (8.2%) stddev-sans-first:13.39 21:48:07 INFO - PROCESS | 2234 | Values: 157.0 125.0 149.0 169.0 146.0 170.0 154.0 153.0 158.0 149.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#2] audio-dft Cycles:10 Average:209.80 Median:197.50 stddev:28.13 (14.2%) stddev-sans-first:19.24 21:48:07 INFO - PROCESS | 2234 | Values: 271.0 254.0 198.0 197.0 194.0 195.0 195.0 197.0 196.0 201.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#3] audio-fft Cycles:10 Average:90.30 Median:57.50 stddev:46.56 (81.0%) stddev-sans-first:47.90 21:48:07 INFO - PROCESS | 2234 | Values: 58.0 88.0 111.0 180.0 56.0 50.0 58.0 84.0 161.0 57.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:77.50 stddev:34.58 (44.6%) stddev-sans-first:35.78 21:48:07 INFO - PROCESS | 2234 | Values: 76.0 80.0 134.0 90.0 79.0 68.0 140.0 65.0 82.0 163.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.00 stddev:1.77 (2.0%) stddev-sans-first:1.76 21:48:07 INFO - PROCESS | 2234 | Values: 91.0 90.0 90.0 88.0 91.0 87.0 91.0 89.0 90.0 86.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:94.50 stddev:18.70 (19.8%) stddev-sans-first:19.29 21:48:07 INFO - PROCESS | 2234 | Values: 120.0 95.0 96.0 112.0 149.0 125.0 94.0 95.0 94.0 96.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:90.50 stddev:17.89 (19.8%) stddev-sans-first:2.42 21:48:07 INFO - PROCESS | 2234 | Values: 147.0 97.0 90.0 90.0 91.0 89.0 89.0 91.0 90.0 91.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:64.00 stddev:5.34 (8.3%) stddev-sans-first:5.34 21:48:07 INFO - PROCESS | 2234 | Values: 59.0 63.0 64.0 64.0 64.0 64.0 65.0 78.0 61.0 59.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:68.00 stddev:12.96 (19.1%) stddev-sans-first:13.33 21:48:07 INFO - PROCESS | 2234 | Values: 63.0 64.0 63.0 70.0 66.0 93.0 62.0 62.0 83.0 94.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.00 stddev:1.34 (1.7%) stddev-sans-first:1.27 21:48:07 INFO - PROCESS | 2234 | Values: 79.0 79.0 82.0 81.0 80.0 81.0 80.0 80.0 83.0 82.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:148.50 stddev:19.74 (13.3%) stddev-sans-first:20.11 21:48:07 INFO - PROCESS | 2234 | Values: 127.0 130.0 130.0 147.0 158.0 123.0 150.0 119.0 168.0 175.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.60 Median:164.00 stddev:1.78 (1.1%) stddev-sans-first:1.87 21:48:07 INFO - PROCESS | 2234 | Values: 163.0 164.0 161.0 165.0 166.0 164.0 161.0 166.0 163.0 163.0 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - PROCESS | 2234 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:2.10 (3.1%) stddev-sans-first:2.20 21:48:07 INFO - PROCESS | 2234 | Values: 66.0 68.0 64.0 65.0 71.0 67.0 67.0 69.0 66.0 65.0 21:48:07 INFO - PROCESS | 2234 | -------- Summary: end -------- 21:48:07 INFO - PROCESS | 2234 | 21:48:07 INFO - TEST-INFO | 2234: exit 0 21:48:07 INFO - TEST-OK | kraken | took 59198ms 21:48:07 INFO - SUITE-END | took 1168s 21:48:08 INFO - Completed test suite (00:19:28) 21:48:08 INFO - javascript benchmark 21:48:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3640.5995340512545, 3656.8537258509677, 3676.209614932789, 3714.0529165062912, 3720.1257704439454, 5895.292564892214, 5974.850628734283, 6016.639001659898, 6018.819435416934, 6044.274687037561, 5939.346671866102, 6009.248843894513, 6019.75920963162, 6045.123457098718, 6046.697665116738, 5316.415194328621, 5347.893042139155, 5372.650777699437, 5422.213779002042, 5456.0, 5312.017276803785, 5342.732863356836, 5374.811881584145, 5431.83704488864, 5450.91823622646, 5383.4616538346245, 5390.083685773408, 5445.754941027663, 5450.5367043801325, 5452.809151679692, 5374.301340825707, 5404.297441332621, 5419.945800541984, 5428.185772134172, 5466.808661696821, 4382.233109205877, 5243.9213411798855, 5275.393329767087, 5314.096603577401, 5366.060939335601, 4878.853634390974, 5761.222234998263, 5785.247917770663, 5802.477777000048, 5811.593188476808, 6290.961991271481, 6293.590916590407, 6359.173307470046, 6363.490920726331, 6386.712597933104, 6224.688765561704, 6265.248170219557, 6276.403741644582, 6302.369763023707, 6302.779402720883, 6119.2350956130485, 6137.570370074097, 6138.355472675349, 6140.8771824563255, 6164.537659675542, 6536.575122617015, 6548.8035358939305, 6558.147440832661, 6563.245226798884, 6567.244766851778, 6250.937490625036, 6266.373362663789, 6315.873682526324, 6320.747170113144, 6327.746890124436, 6352.523560732963, 6353.650549219838, 6362.650054247061, 6362.981922892408, 6395.232572091286, 6239.656818875005, 6248.968755156286, 6260.311365749764, 6306.990881459036, 6321.494280457552, 6202.0696895465135, 6231.626102433868, 6297.244330680348, 6299.338569450233, 6317.210348706411, 5052.823151189691, 5093.981203759263, 5112.079825631347, 5119.206522988943, 5131.999260144236, 4510.436195475566, 4524.819007239739, 4598.908021839545, 4603.424571928509, 4618.745968971739, 6079.331273559905, 6085.908711369333, 6201.441870231701, 6208.50331973441, 6222.937770622327, 6323.114763933053, 6366.7771627993725, 6379.681015949184, 6380.457661098859, 6393.712282947278, 6098.390160983955, 6135.877282454415, 6173.722182501798, 6218.751249950042, 6218.906716399258, 5518.44815518453, 5560.610757247035, 5631.8592035199445, 5656.943430565724, 5664.490195882308, 6614.0740296358545, 6619.569727967667, 6643.7674681385915, 6646.800595982225, 6672.266050734415, 6372.840678983062, 6393.808185754435, 6399.072134540373, 6401.711922963571, 6409.7115629795835, 5513.61404701667, 6021.759129634941, 6538.738450461929, 6677.2655007948115, 6698.229703583956, 6253.1245625613365, 6253.906191407042, 6306.180196574416, 6317.336679648754, 6339.587926784929, 6248.781292654735, 6381.553291269566, 6394.904076438764, 6416.679166041773, 6444.967775161094, 6128.601640893327, 6165.383461653978, 6224.190855188796, 6274.278457977391, 6282.623042617458, 6259.96870015647, 6291.182146320949, 6295.244570651551, 6299.748010079546, 6317.5893566918, 6032.969835150796, 6111.205543279345, 6199.008158694587, 6218.626882387071, 6252.187215661935, 6264.498840092872, 6268.529860260407, 6304.306526282197, 6326.430621243926, 6342.429181373698, 6219.0982307566055, 6276.372362763687, 6281.403266689657, 6285.717142728679, 6311.652859092794], "value": 5893.460137734226, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.1175051048821, 547.7288742072674, 556.1685280505644, 599.9640021598707, 624.7750809708505, 38687.61312386875, 39738.0, 39824.60175398245, 39875.800620996924, 40201.79899100504, 40662.0, 40698.59301406985, 40725.0, 41258.58741412585, 41346.7932660337, 9651.0, 9684.176844968151, 9689.660861869836, 9709.320347575673, 9889.653862114827, 9894.851577226347, 10080.042395972407, 10089.344192627492, 10097.798044039115, 10124.03821636947, 9913.801723965516, 10040.799184016316, 10100.747481312952, 10102.040306170938, 10109.090181883628, 9848.852267215962, 9895.406275623485, 9963.800723985516, 10058.094771470565, 10090.243231757648, 9748.31761776676, 9781.90218097817, 9782.804343913152, 9881.25890558211, 9930.304878658424, 40266.798666006776, 40370.0, 40712.0, 40716.79641601803, 40720.98197545055, 3350.9832450837835, 3430.5711786026745, 3559.804210768407, 3637.6907962823193, 3898.1034362096593, 29004.12987610375, 29817.552736708967, 29927.40145197084, 30143.0, 30148.69851301481, 2287.622542280522, 2826.858657067138, 2842.6588809342807, 2845.4166895786316, 2907.5202591572574, 485478.0, 489887.0, 490750.0, 491982.0, 492237.0, 51946.48053519492, 55766.048188313216, 65014.0, 66282.0, 66331.0, 68746.65626671835, 76677.0, 76950.0, 77071.0, 77087.61456192796, 19044.61910761777, 19282.0, 19311.903440482987, 19518.219271229274, 19522.0, 14866.776998345034, 14933.17867517297, 15016.24918754058, 15048.699026019418, 15057.623559411102, 20965.161393544393, 21219.78780212178, 21245.68131478029, 21283.574328513343, 21345.46636334073, 8707.782305442415, 8744.562771861381, 8749.825003499895, 8752.21230089295, 8866.556672166365, 998.4773220838134, 1037.7405648587853, 1067.9199060070525, 1156.0173852225541, 1234.2841152131741, 31858.681413186034, 32079.0, 32624.510632340534, 33273.0, 34271.65728342735, 1033.7932413517176, 1065.8401239813934, 1169.824326551827, 1220.926744395339, 1225.2770865189582, 15301.311440985182, 15489.07065576069, 15564.688706225812, 15682.76475852863, 15733.370665173265, 660982.3901761017, 663887.0, 664606.0, 669336.0, 672834.0, 61332.69333653392, 62015.0, 62248.06627900585, 62314.376856231764, 62491.375086248554, 1563.3277690593154, 1579.8104227492774, 1582.4303250829691, 1591.124881315272, 1663.3679201903442, 30312.0, 30675.693243067282, 30799.846000769852, 31105.222369440944, 31345.686543134278, 55547.444525555846, 56069.87860242854, 56461.0, 56636.0, 56656.433435665116, 612834.0, 615618.0, 618869.0, 619012.0, 620633.0, 26885.0, 27374.0, 27804.19272747265, 27837.02570410026, 28005.43989120247, 45823.0, 46079.0, 47067.0, 47363.763181185255, 47577.33479328207, 38705.6129438702, 38960.0, 39266.0, 39345.0, 39650.41398344034, 38473.230535389695, 38514.80742596269, 38845.805770972096, 38919.0, 39628.60371396249, 33258.50112248366, 38102.666406675635, 38287.23425531418, 38306.80846595749, 38715.22569548649, 31722.0, 33463.16342091467, 33497.330053398306, 33666.0, 33741.49387759136, 25519.10683126156, 25643.35891602725, 25655.230343089737, 25785.849715597094, 26061.957521698918], "value": 20956.341689936602, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.1033767063144, 416.8645190313148, 419.2997693851268, 421.6563500746891, 428.3638796387366, 21208.045637946292, 21264.89367553162, 21929.23247686331, 23149.0, 23221.76778232217, 22420.55158896821, 22447.87760611971, 22564.43588910274, 22685.886570567167, 23117.0, 5964.821055368335, 6233.158523599324, 6291.0, 6294.0873573331955, 6301.306856245809, 4554.84058057968, 6132.417420345082, 6261.46777523911, 6416.45460135889, 6918.411934985532, 6741.8314542136595, 6748.797536073901, 6748.932510674879, 6823.55646882951, 6854.2117656469645, 6552.3775241352, 6700.095487109251, 6721.193456785169, 6722.4285935695525, 6837.555558888656, 5933.376995415506, 6102.633841969496, 6113.969430152865, 6132.294786099566, 7239.276072392771, 29700.0, 29956.400871982438, 30024.099277021724, 30050.0, 30480.69519304823, 1988.443235893952, 2048.4366799130207, 2338.0414030247516, 2366.9403099966644, 2515.434027343851, 15793.842061579351, 16470.941132358454, 16543.172841357886, 17196.484105476855, 17526.29894804219, 1904.9998750656016, 1922.1062206074103, 1938.6995015772577, 1974.2300502803919, 1997.0514005847251, 93401.0, 130143.69856301504, 136442.0, 139694.0, 140296.0, 18963.146658400405, 22340.776592234193, 22860.7713922862, 23141.53716925652, 23268.418289542897, 36741.0, 36908.0, 37456.0, 37716.0, 38129.80935095362, 10311.742206444898, 11574.668913074936, 12095.092868034933, 12207.145499815106, 12226.327551984548, 10080.798384032278, 10110.646127385506, 10132.594696212216, 10169.186465082776, 10235.130013948748, 9356.532173391302, 10714.624988125533, 10797.62208322705, 10904.50929708165, 10905.727356815984, 1837.3477415517566, 2078.002558771798, 2111.9472013199793, 2154.439845640145, 4077.1845630873945, 811.9309858661961, 966.2270183852889, 991.9305648604673, 1050.0811789684026, 1057.3814318623538, 21560.892195539236, 22668.206612768474, 22689.746738142774, 22760.544789104126, 22775.430614234447, 1020.3367810922811, 1022.6420752736632, 1047.1988928469727, 1064.5741703318579, 1067.1622776120707, 11652.708682283012, 12010.879891200977, 12107.273563586214, 12143.574974875837, 12232.938835305766, 677471.4505709858, 682371.0, 692195.0, 702857.0, 713534.0, 139982.0, 140754.0, 148758.0, 150102.0, 150113.24943375212, 1103.5034234594464, 1112.5438570186184, 1119.3060302612269, 1124.3872089710962, 1129.3167633581565, 19417.93201373938, 19923.900380498006, 20086.0, 20100.69848952296, 20162.19351225993, 57372.42627573671, 58250.252492425294, 58285.0, 59776.70111649588, 60153.69923150356, 71073.0, 77127.61436192783, 88082.11917880995, 89856.10143898477, 90691.54654226686, 8231.46495477792, 8365.707200248205, 8401.537915414467, 8533.018702849251, 8651.875256216652, 9352.298577606844, 12535.561255356019, 12883.935580322039, 13172.934135329262, 13309.733805323645, 11095.667129986114, 11392.0, 11547.307161570332, 11671.241369310968, 11693.064554835762, 11127.721806954567, 11290.43547822622, 11292.943535282271, 11547.4803633835, 11598.0, 9264.351495395258, 11549.960503554721, 11732.0, 11883.405829708307, 11941.82087268709, 8317.792055198668, 8509.0, 8519.06290308078, 8545.444546104483, 8566.742997710078, 9226.630934762803, 11204.943975280072, 11427.714307142389, 11658.475368608264, 11717.88282117168], "value": 11257.052135167032, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1644.6381796004878, 1676.6395225026617, 1966.2331690640654, 1992.1135094882766, 2011.7887621799712, 7110.1112360954885, 7758.379329653629, 7963.8805417918775, 8327.333813294937, 9434.905650943498, 6495.935040649592, 9077.500737459437, 9139.543022848848, 9200.0, 9231.076892310766, 2197.8351623628214, 2473.0107956817237, 2534.8352357096774, 2551.0561092395837, 2565.7947364210913, 2490.2155820916446, 2561.4108754986273, 2561.820672552922, 2566.5893457046877, 2569.3062873024273, 2137.572485502898, 2488.104282458323, 2527.279725278296, 2552.208815267273, 2594.9351266218305, 2519.2316343515313, 2523.129520315488, 2547.7197508274076, 2580.729023452548, 2581.277242372139, 2529.962050569243, 2572.0, 2574.9485010299877, 2587.4178309880313, 2597.6103584462294, 9312.813743725155, 9343.0, 9383.014783447776, 9383.624655013791, 9387.014363491871, 5188.45521220274, 5698.458646428583, 5711.371749107595, 5720.0, 5892.970535147339, 3634.3821550336506, 3712.7215458840697, 3759.8684046058256, 3770.5671844699114, 3777.6788972937334, 893.8569828827422, 1071.2394200117926, 1071.7588542577935, 1075.7364445710773, 1096.4298564746366, 12179.269243845398, 12222.144449888423, 12681.36593170347, 12686.365681715877, 12699.682507937283, 4507.233770259064, 4824.517548245218, 5055.595552355802, 5081.856582269033, 5178.6374953752875, 10495.212859035602, 10504.527296271686, 10771.569137234423, 10777.568897244022, 10785.029347358775, 5675.2054712340305, 5713.371529131792, 5713.8285851424525, 5859.062549991981, 5869.442402971797, 4684.320773487893, 5091.821786237464, 5136.1525348317855, 5176.3270774799785, 5226.111561034633, 4189.958100419018, 4266.722663026893, 4349.9130017399475, 4353.36876152956, 4364.825406983685, 1384.819973403451, 1406.9507567235098, 1408.3169662713576, 1418.9432422702976, 1424.302091974925, 953.3945944325403, 984.3601658921758, 1004.076249850125, 1009.258195226508, 1022.257051726804, 6116.877662446726, 6152.753889844356, 6235.937640623626, 6254.982461734759, 6267.96866015667, 442.6388854273123, 458.60789025382815, 483.9201531747222, 497.9203327467588, 500.05489624609515, 4084.1219137885496, 4090.2637525245145, 4105.774182419996, 4109.589041095866, 4117.279476091732, 10848.403337816495, 11066.336019838835, 11176.552937882556, 11286.040686541572, 11331.943340283246, 10912.23614347004, 10937.83593246102, 11025.062869656009, 11125.109991200685, 11133.165012623924, 8578.313734901318, 8625.568721563897, 8664.523451210118, 8667.393282470293, 8794.076621954731, 5840.561957853093, 5956.791512297049, 6071.605345652519, 6077.544184186116, 6185.319614842454, 9893.109620134222, 9947.801043979225, 9957.004299569984, 9991.500424978576, 10064.295499314978, 10099.899001009895, 10356.482175891024, 10482.0, 10507.15942724565, 10645.733856653645, 7617.923820761722, 7902.209779022051, 7912.802179945547, 7921.1682773307975, 7968.0438347398685, 8677.913220867711, 8684.522351270742, 8722.389432739647, 8733.868991964999, 8764.474131552128, 7593.202713715202, 7750.147483776667, 7751.844963100819, 7752.8837067445165, 7837.6081195941115, 7573.9242607573215, 7651.961740191264, 7725.729599463992, 7782.066152061789, 7811.414143939113, 5387.191921211849, 6335.904961425675, 6666.933290673469, 7253.927460725537, 7436.293552112541, 4996.775145118406, 5038.319826823332, 5116.820911268088, 5212.582993360446, 5213.452587478412, 4512.864614061584, 4628.83799067031, 5069.112905241494, 5118.897622047463, 5272.156454967187], "value": 4713.008972882624, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.87937735435395, 332.1679193619985, 337.76356550414715, 343.0600155573728, 347.75135777918786, 6686.364795344447, 6757.054012438262, 6845.349691779279, 6857.108575885134, 6867.58794472332, 4939.901201975958, 6669.066330713704, 6774.9322506774915, 6860.897086543706, 6875.44996400288, 5094.566961808251, 5102.464241254652, 5112.003159383912, 5113.156329205676, 5126.41046279677, 4167.395727619493, 5096.719680417576, 5108.080545501808, 5118.334616499868, 5128.461511541291, 5053.823116190934, 5057.772400241998, 5087.0, 5089.51649593288, 5091.439941606421, 4672.859814205545, 4881.316615673809, 5068.1890897456415, 5073.949260507403, 5086.0, 4111.848682368942, 4138.13099249158, 4141.689373297015, 4152.045029643169, 4153.771542565158, 6478.060681201199, 6496.870062598721, 6538.77114301002, 6540.869182616369, 6602.207735071774, 5766.221560089376, 5828.417158284137, 5866.266716660418, 5872.559558033165, 5905.202797622309, 5938.8812223755285, 6948.27043160471, 6989.33601307875, 7177.4258059355125, 7186.02988596538, 4561.657875659339, 4599.678022538424, 4682.906341873144, 4736.881577960544, 4759.595434388081, 7726.227377262285, 7778.494397864178, 7831.334336581397, 7842.8823567647105, 7848.0, 4791.832285869978, 4809.735464549483, 4810.735409552508, 4813.638977076734, 4821.831235906727, 4889.4377146627885, 4896.143174944371, 4899.706017638881, 4910.238912968424, 4923.187674033816, 5489.725513724298, 5583.162525621108, 5795.565332599987, 5892.616979896293, 5901.878643057806, 7191.532550384313, 7304.050473438419, 7389.926100739031, 7391.113066431955, 7393.334599885929, 1668.4994501649458, 1688.5525335786026, 1694.9745753813702, 1696.872734544914, 1699.9830001699825, 980.3970558106713, 994.433173091331, 1058.9939557420482, 1080.400377790334, 1085.1698450685233, 6943.9652801735665, 6962.094927659372, 6968.0941477607585, 6991.825204369931, 6996.930030699729, 7575.734849280248, 7585.1656317805, 7599.050118735158, 7606.771796846104, 7634.0, 6933.064036355128, 6947.305269473113, 6952.895706564405, 6990.196127445307, 6995.26549712283, 3885.6502914737807, 3888.319544079775, 3997.560268370477, 4027.154297597479, 4048.514178298623, 7832.882506762403, 8009.479383840032, 8047.195280471906, 8060.556669383123, 8110.026796784424, 7900.920990790019, 7911.248431399008, 7916.881246781303, 7936.523808571505, 7947.3642108631175, 6385.776497822554, 8055.234752698601, 8094.554799485967, 8106.513609183467, 8122.228388303101, 7295.4528410368375, 7500.774976750707, 7614.581198034161, 7685.0, 7737.806554836174, 7529.435292353205, 7561.6597253122645, 7575.469717119749, 7587.886181707168, 7588.317051465395, 6471.223453185459, 8077.0, 8105.837883242184, 8123.309518690978, 8136.87794683092, 3926.1362500249897, 4757.072370887644, 4830.686005409637, 4831.879203019953, 4839.661223714306, 7316.634168291671, 7571.091469023752, 7576.24237576238, 7577.1665116836, 7678.577678227751, 7222.313880181375, 7333.6333183341685, 7393.704251829867, 7404.222556631476, 7405.333519983227, 7365.963170184329, 7408.185099638928, 7412.369948554433, 7431.88521721746, 7469.439792015729, 5673.773049078156, 7034.894844511051, 7194.892076618959, 7212.783616491513, 7244.0, 7328.926710732824, 7350.816229594303, 7362.042934418491, 7371.336579707852, 7391.525982788081, 6457.0, 6594.043863639649, 7194.136703595393, 7217.494775365674, 7233.421326293778], "value": 5322.6777298787665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.6123324021648, 782.077148964222, 786.5045021636369, 791.9326857217136, 793.1711361627099, 15696.0, 15723.213839308046, 15956.96279741816, 15984.440544580943, 16040.037597744144, 14803.92598037011, 14849.77725334118, 14871.851281487208, 14923.0, 14943.551693449213, 790.7548659915444, 977.4575110813496, 994.2592768387561, 996.8853581838109, 998.0518507417946, 911.5487833522383, 931.1107891963159, 932.342698397628, 934.22926085979, 979.6913972098802, 991.4794732765283, 991.6033586565395, 1000.5097502223895, 1041.5209003858233, 1041.7916416716648, 986.3588667366233, 993.2004736187366, 993.7366597851575, 1031.9742006449862, 1035.4563853976647, 746.4588173574169, 746.7573038762424, 747.2116916652928, 747.4805010517692, 784.2040329066002, 14905.925470372687, 15373.308201130976, 15396.0, 15448.382064717398, 15455.459058932996, 13278.269695166762, 13285.667858303523, 13354.599362019155, 13429.261390623511, 13486.258255795927, 9983.500824958795, 10290.485475726258, 10393.06462418386, 10457.006584374472, 10639.0, 3548.201654627714, 3609.657082577151, 3630.4009838376637, 3633.9273214535824, 3863.787491687956, 87436.5628171855, 90077.0, 91298.0, 91595.6260656084, 92888.53555732245, 9828.361156524803, 9851.605935762633, 9949.403035817873, 10091.798164036678, 10117.443540605338, 22461.32616021522, 22596.435089122904, 22934.96792644335, 23353.832308384513, 23433.0, 7804.297613214838, 7903.0, 7927.801804954922, 7984.481008734414, 8088.595570221465, 4249.150169966019, 4261.531231564525, 4265.296226122717, 4274.401583778274, 4303.827846886152, 3694.556653201633, 3806.467094606757, 3864.8598663393805, 3866.5940076292145, 3871.3031654302495, 708.0724251230904, 711.8042538302009, 724.2974314914525, 730.3682314797662, 731.7841236835146, 507.77150282373077, 541.0288532084917, 559.700560200299, 567.5658121537027, 586.7535635033296, 8108.905297784723, 8191.713290034821, 8225.341972642174, 8293.95853020743, 8419.915800841913, 421.7848897062459, 494.08840688929587, 537.8010136249612, 582.9213056237438, 588.5144755576666, 3711.2391959648207, 3824.9426258606145, 3850.595687452777, 3865.903352416212, 4056.8377264909072, 78271.0, 94834.52582737042, 95054.5247273773, 101814.0, 104365.47817260865, 80602.0, 83858.58070709607, 86307.56846215729, 86937.26125477455, 89274.10725892788, 74585.0, 74996.62501687456, 76730.46539069299, 77181.99999999888, 77732.61133694409, 8681.782955426164, 8765.07966663491, 8817.823643526965, 8861.202491775772, 8954.686585969459, 41221.0, 48051.75974120247, 50110.24834627411, 50441.74779126081, 52706.73646631742, 68849.3115068863, 68883.65558172177, 70051.0, 70261.0, 73076.0, 11083.612073577386, 11132.777344453227, 11165.050970667584, 11186.664400067686, 11539.4807233673, 9980.800383992424, 12001.459934302802, 12200.450979705756, 12358.567450139202, 12611.0, 11697.067789373383, 11838.171328006956, 12014.399280036137, 12101.273923564615, 12105.515779368727, 11742.765144697229, 11824.172307938707, 12081.0, 12119.454624542093, 12269.754604907674, 11686.18196726221, 11731.296122232694, 11750.059995200534, 11879.46542405611, 11966.922976932117, 9841.655542055994, 10045.0, 10087.546060427152, 10366.637167699095, 10400.895991039994, 9832.427921016762, 9971.551280192554, 9974.251931105342, 10004.699859004242, 10091.44497052669], "value": 7204.051251919185, "unit": "score"}], "name": "dromaeo_css", "value": 7943.753986468862, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 120.0, 109.0, 138.0, 178.0, 160.0, 105.0, 115.0, 140.0, 148.0], "value": 132.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 149.0, 169.0, 146.0, 170.0, 154.0, 153.0, 158.0, 149.0], "value": 153.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [271.0, 254.0, 198.0, 197.0, 194.0, 195.0, 195.0, 197.0, 196.0, 201.0], "value": 209.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 88.0, 111.0, 180.0, 56.0, 50.0, 58.0, 84.0, 161.0, 57.0], "value": 90.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 80.0, 134.0, 90.0, 79.0, 68.0, 140.0, 65.0, 82.0, 163.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 90.0, 88.0, 91.0, 87.0, 91.0, 89.0, 90.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 95.0, 96.0, 112.0, 149.0, 125.0, 94.0, 95.0, 94.0, 96.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 97.0, 90.0, 90.0, 91.0, 89.0, 89.0, 91.0, 90.0, 91.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 64.0, 64.0, 64.0, 64.0, 65.0, 78.0, 61.0, 59.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 70.0, 66.0, 93.0, 62.0, 62.0, 83.0, 94.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 82.0, 81.0, 80.0, 81.0, 80.0, 80.0, 83.0, 82.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 130.0, 130.0, 147.0, 158.0, 123.0, 150.0, 119.0, 168.0, 175.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 161.0, 165.0, 166.0, 164.0, 161.0, 166.0, 163.0, 163.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 64.0, 65.0, 71.0, 67.0, 67.0, 69.0, 66.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1566.7, "alertThreshold": 2.0}]} 21:48:08 INFO - Return code: 0 21:48:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:48:08 ERROR - # TBPL SUCCESS # 21:48:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:48:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:48:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:48:08 INFO - 2016-06-23 21:48:08.367 screenresolution[2239:22474] starting screenresolution argv=screenresolution get 21:48:08 INFO - 2016-06-23 21:48:08.370 screenresolution[2239:22474] Display 0: 1600x1200x32@60 21:48:08 INFO - 2016-06-23 21:48:08.376 screenresolution[2240:22477] starting screenresolution argv=screenresolution list 21:48:08 INFO - Available Modes on Display 0 21:48:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:48:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:48:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:48:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:48:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:48:08 INFO - Intel Iris: 21:48:08 INFO - Chipset Model: Intel Iris 21:48:08 INFO - Type: GPU 21:48:08 INFO - Bus: Built-In 21:48:08 INFO - VRAM (Dynamic, Max): 1536 MB 21:48:08 INFO - Vendor: Intel (0x8086) 21:48:08 INFO - Device ID: 0x0a2e 21:48:08 INFO - Revision ID: 0x0009 21:48:08 INFO - Displays: 21:48:08 INFO - Display: 21:48:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:48:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:48:08 INFO - Display Serial Number: 1600x1200 60 21:48:08 INFO - Main Display: Yes 21:48:08 INFO - Mirror: Off 21:48:08 INFO - Online: Yes 21:48:08 INFO - Rotation: Supported 21:48:08 INFO - Return code: 0 21:48:08 INFO - Running post-action listener: _resource_record_post_action 21:48:08 INFO - Running post-run listener: _resource_record_post_run 21:48:09 INFO - Total resource usage - Wall time: 1193s; CPU: 53.0%; Read bytes: 339968; Write bytes: 496987136; Read time: 3; Write time: 1590 21:48:09 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 186870272; Write bytes: 186054656; Read time: 17647; Write time: 419 21:48:09 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 339968; Write bytes: 300143616; Read time: 3; Write time: 1135 21:48:09 INFO - Running post-run listener: _upload_blobber_files 21:48:09 INFO - Blob upload gear active. 21:48:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:48:09 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 21:48:09 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'] 21:48:09 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 21:48:10 INFO - (blobuploader) - INFO - Open directory for files ... 21:48:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:48:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:48:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:11 INFO - (blobuploader) - INFO - Done attempting. 21:48:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:48:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:48:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:11 INFO - (blobuploader) - INFO - Done attempting. 21:48:11 INFO - (blobuploader) - INFO - Iteration through files over. 21:48:12 INFO - Return code: 0 21:48:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:48:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:48:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d1187e87af1387b847685e1eb30389c31e892aa55c917cad9537ec72cca21a10f79f97c97501b6a24e31e77b6d33bb92ff27c4a0125217765be607c905541ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28f9e8624104d8a2ce6cae3c5b364927b8dd67f3901ac78a1519d9e3123cd7f94f05f6c6ef361040b006b567d3a0c15a29f26d8adb4982374f634d5ebbfa774"} 21:48:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:48:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:48:12 INFO - Contents: 21:48:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d1187e87af1387b847685e1eb30389c31e892aa55c917cad9537ec72cca21a10f79f97c97501b6a24e31e77b6d33bb92ff27c4a0125217765be607c905541ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28f9e8624104d8a2ce6cae3c5b364927b8dd67f3901ac78a1519d9e3123cd7f94f05f6c6ef361040b006b567d3a0c15a29f26d8adb4982374f634d5ebbfa774"} 21:48:12 INFO - Running post-run listener: copy_logs_to_upload_dir 21:48:12 INFO - Copying logs to upload dir... 21:48:12 INFO - mkdir: /builds/slave/test/build/upload/logs 21:48:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1312.170292 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-06-23 21:48:12.465868) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 21:48:12.471177) ========= 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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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/4d1187e87af1387b847685e1eb30389c31e892aa55c917cad9537ec72cca21a10f79f97c97501b6a24e31e77b6d33bb92ff27c4a0125217765be607c905541ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28f9e8624104d8a2ce6cae3c5b364927b8dd67f3901ac78a1519d9e3123cd7f94f05f6c6ef361040b006b567d3a0c15a29f26d8adb4982374f634d5ebbfa774"} build_url:https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011051 build_url: 'https://queue.taskcluster.net/v1/task/R0YGKWOvQYiX7NXbCUmJHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d1187e87af1387b847685e1eb30389c31e892aa55c917cad9537ec72cca21a10f79f97c97501b6a24e31e77b6d33bb92ff27c4a0125217765be607c905541ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f28f9e8624104d8a2ce6cae3c5b364927b8dd67f3901ac78a1519d9e3123cd7f94f05f6c6ef361040b006b567d3a0c15a29f26d8adb4982374f634d5ebbfa774"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 21:48:12.503859) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 21:48:12.504192) ========= 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.Ep9gE2iRMA/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.wJINXDJeZg/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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.005297 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 21:48:12.553697) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 21:48:12.554019) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 21:48:12.554488) ========= ========= Total master_lag: 0.61 =========