builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0234 starttime: 1464719669.19 results: success (0) buildid: 20160531103942 builduid: e71bbbe81f194a8996e2dd81575c8890 revision: 9eebb2835273857468a80e0e89013673352a37e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:29.189147) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:29.189528) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-05-31 11:34:29.189828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-05-31 11:34:36.694578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-31 11:34:36.694888) ========= 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.n2qUY2FDUZ/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.D001pJKNp4/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-31 11:34:36-- 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% 16.4M=0.001s 2016-05-31 11:34:37 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.580055 ========= master_lag: 3.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-31 11:34:41.044414) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:41.047778) ========= 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.n2qUY2FDUZ/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.D001pJKNp4/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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.021153 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:41.113134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 11:34:41.113450) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9eebb2835273857468a80e0e89013673352a37e7 --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-aurora --rev 9eebb2835273857468a80e0e89013673352a37e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n2qUY2FDUZ/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.D001pJKNp4/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-31 11:34:41,170 truncating revision to first 12 chars 2016-05-31 11:34:41,170 Setting DEBUG logging. 2016-05-31 11:34:41,170 attempt 1/10 2016-05-31 11:34:41,170 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9eebb2835273?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 11:34:42,487 unpacking tar archive at: mozilla-aurora-9eebb2835273/testing/mozharness/ program finished with exit code 0 elapsedTime=1.595569 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 11:34:42.740620) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:42.740912) ========= script_repo_revision: 9eebb2835273857468a80e0e89013673352a37e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:42.741299) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:42.741599) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 11:34:42.757780) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-31 11:34:42.758052) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n2qUY2FDUZ/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.D001pJKNp4/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:34:42 INFO - MultiFileLogger online at 20160531 11:34:42 in /builds/slave/test 11:34:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:34:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:34:42 INFO - {'append_to_log': False, 11:34:42 INFO - 'base_work_dir': '/builds/slave/test', 11:34:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:34:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:34:42 INFO - 'branch': 'Mozilla-Aurora', 11:34:42 INFO - 'buildbot_json_path': 'buildprops.json', 11:34:42 INFO - 'config_files': ('talos/mac_config.py',), 11:34:42 INFO - 'default_actions': ('clobber', 11:34:42 INFO - 'read-buildbot-config', 11:34:42 INFO - 'download-and-extract', 11:34:42 INFO - 'populate-webroot', 11:34:42 INFO - 'create-virtualenv', 11:34:42 INFO - 'install', 11:34:42 INFO - 'run-tests'), 11:34:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:34:42 INFO - 'download_minidump_stackwalk': True, 11:34:42 INFO - 'download_symbols': 'ondemand', 11:34:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:34:42 INFO - 'tooltool.py': '/tools/tooltool.py', 11:34:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:34:42 INFO - '/tools/misc-python/virtualenv.py')}, 11:34:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:34:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:34:42 INFO - 'installer_path': 'installer.exe', 11:34:42 INFO - 'log_level': 'info', 11:34:42 INFO - 'log_name': 'talos', 11:34:42 INFO - 'log_to_console': True, 11:34:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:34:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:34:42 INFO - 'opt_config_files': (), 11:34:42 INFO - 'pip_index': False, 11:34:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:34:42 INFO - 'cmd': ('bash', 11:34:42 INFO - '-c', 11:34:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:34:42 INFO - 'enabled': True, 11:34:42 INFO - 'halt_on_failure': False, 11:34:42 INFO - 'name': 'check_screen_resolution'},), 11:34:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:34:42 INFO - 'cmd': ('bash', 11:34:42 INFO - '-c', 11:34:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:34:42 INFO - 'enabled': True, 11:34:42 INFO - 'halt_on_failure': False, 11:34:42 INFO - 'name': 'check_screen_resolution'},), 11:34:42 INFO - 'run_cmd_checks_enabled': True, 11:34:42 INFO - 'sps_profile': False, 11:34:42 INFO - 'sps_profile_interval': 0, 11:34:42 INFO - 'suite': 'dromaeojs', 11:34:42 INFO - 'system_bits': '32', 11:34:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:34:42 INFO - 'title': 't-yosemite-r7-0234', 11:34:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:34:42 INFO - 'use_talos_json': True, 11:34:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:34:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:34:42 INFO - 'work_dir': 'build'} 11:34:42 INFO - ##### 11:34:42 INFO - ##### Running clobber step. 11:34:42 INFO - ##### 11:34:42 INFO - Running pre-action listener: _resource_record_pre_action 11:34:42 INFO - Running main action method: clobber 11:34:42 INFO - rmtree: /builds/slave/test/build 11:34:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:34:44 INFO - Running post-action listener: _resource_record_post_action 11:34:44 INFO - ##### 11:34:44 INFO - ##### Running read-buildbot-config step. 11:34:44 INFO - ##### 11:34:44 INFO - Running pre-action listener: _resource_record_pre_action 11:34:44 INFO - Running main action method: read_buildbot_config 11:34:44 INFO - Using buildbot properties: 11:34:44 INFO - { 11:34:44 INFO - "project": "", 11:34:44 INFO - "product": "firefox", 11:34:44 INFO - "script_repo_revision": "production", 11:34:44 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:34:44 INFO - "repository": "", 11:34:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:34:44 INFO - "buildid": "20160531103942", 11:34:44 INFO - "pgo_build": "False", 11:34:44 INFO - "basedir": "/builds/slave/test", 11:34:44 INFO - "buildnumber": 27, 11:34:44 INFO - "slavename": "t-yosemite-r7-0234", 11:34:44 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:34:44 INFO - "platform": "yosemite_r7", 11:34:44 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:34:44 INFO - "branch": "mozilla-aurora", 11:34:44 INFO - "repo_path": "releases/mozilla-aurora", 11:34:44 INFO - "revision": "9eebb2835273857468a80e0e89013673352a37e7", 11:34:44 INFO - "stage_platform": "macosx64", 11:34:44 INFO - "builduid": "e71bbbe81f194a8996e2dd81575c8890", 11:34:44 INFO - "slavebuilddir": "test" 11:34:44 INFO - } 11:34:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 11:34:44 INFO - Running post-action listener: _resource_record_post_action 11:34:44 INFO - ##### 11:34:44 INFO - ##### Running download-and-extract step. 11:34:44 INFO - ##### 11:34:44 INFO - Running pre-action listener: _resource_record_pre_action 11:34:44 INFO - Running main action method: download_and_extract 11:34:44 INFO - mkdir: /builds/slave/test/build/tests 11:34: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')]} 11:34:44 INFO - https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:34:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 11:34:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 11:34:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 11:34:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 11:34:57 INFO - Downloaded 1199 bytes. 11:34:57 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 11:34:57 INFO - Using the following test package requirements: 11:34:57 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 11:34:57 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 11:34:57 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 11:34:57 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'jsshell-mac.zip'], 11:34:57 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 11:34:57 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 11:34:57 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 11:34:57 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 11:34:57 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 11:34:57 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:34:57 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 11:34:57 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:34: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')]} 11:34:57 INFO - https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:34:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:34:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:34:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:34:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:35:04 INFO - Downloaded 18463790 bytes. 11:35:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:35:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:35:07 INFO - Return code: 0 11:35:07 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 11:35: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')]} 11:35:07 INFO - https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:35:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:35:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:35:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:35:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:35:13 INFO - Downloaded 18463790 bytes. 11:35:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:35:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:35:17 INFO - Return code: 0 11:35:17 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')]} 11:35:17 INFO - https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:35:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:35:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:35:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:35:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:35:17 INFO - Downloaded 10840145 bytes. 11:35:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:35:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:35:18 INFO - Return code: 0 11:35:18 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')]} 11:35:18 INFO - https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 11:35:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:35:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:35:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 11:35:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 11:35:24 INFO - Downloaded 114157187 bytes. 11:35:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:35:24 INFO - mkdir: /builds/slave/test/properties 11:35:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:35:24 INFO - Writing to file /builds/slave/test/properties/build_url 11:35:24 INFO - Contents: 11:35:24 INFO - build_url:https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:35:25 INFO - Running post-action listener: _resource_record_post_action 11:35:25 INFO - Running post-action listener: set_extra_try_arguments 11:35:25 INFO - ##### 11:35:25 INFO - ##### Running populate-webroot step. 11:35:25 INFO - ##### 11:35:25 INFO - Running pre-action listener: _resource_record_pre_action 11:35:25 INFO - Running main action method: populate_webroot 11:35:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:35:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:35:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:35:25 INFO - '--tppagecycles', 11:35:25 INFO - '7'], 11:35:25 INFO - 'tests': ['tsvgm']}}, 11:35:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:35:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:35:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:35:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:35:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:35:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:35:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['damp', 'tps']}, 11:35:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'tests': ['damp', 'tps']}, 11:35:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['dromaeo_dom']}, 11:35:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:35:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['a11yr', 11:35:25 INFO - 'ts_paint', 11:35:25 INFO - 'tpaint', 11:35:25 INFO - 'sessionrestore', 11:35:25 INFO - 'sessionrestore_no_auto_restore', 11:35:25 INFO - 'tabpaint']}, 11:35:25 INFO - 'other-e10s': {'tests': ['a11yr', 11:35:25 INFO - 'ts_paint', 11:35:25 INFO - 'tpaint', 11:35:25 INFO - 'sessionrestore', 11:35:25 INFO - 'sessionrestore_no_auto_restore', 11:35:25 INFO - 'tabpaint']}, 11:35:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:35:25 INFO - 'ts_paint', 11:35:25 INFO - 'tpaint', 11:35:25 INFO - 'sessionrestore', 11:35:25 INFO - 'sessionrestore_no_auto_restore', 11:35:25 INFO - 'tabpaint']}, 11:35:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:35:25 INFO - 'ts_paint', 11:35:25 INFO - 'tpaint', 11:35:25 INFO - 'sessionrestore', 11:35:25 INFO - 'sessionrestore_no_auto_restore', 11:35:25 INFO - 'tabpaint']}, 11:35:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['a11yr', 11:35:25 INFO - 'ts_paint', 11:35:25 INFO - 'tpaint', 11:35:25 INFO - 'sessionrestore', 11:35:25 INFO - 'sessionrestore_no_auto_restore', 11:35:25 INFO - 'tabpaint']}, 11:35:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['a11yr', 11:35:25 INFO - 'ts_paint', 11:35:25 INFO - 'tpaint', 11:35:25 INFO - 'sessionrestore', 11:35:25 INFO - 'sessionrestore_no_auto_restore', 11:35:25 INFO - 'tabpaint']}, 11:35:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['tsvgx', 11:35:25 INFO - 'tsvgr_opacity', 11:35:25 INFO - 'tart', 11:35:25 INFO - 'tscrollx', 11:35:25 INFO - 'cart']}, 11:35:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:35:25 INFO - 'tsvgr_opacity', 11:35:25 INFO - 'tart', 11:35:25 INFO - 'tscrollx', 11:35:25 INFO - 'cart']}, 11:35:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'talos_options': ['--disable-e10s'], 11:35:25 INFO - 'tests': ['tp5o']}, 11:35:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'tests': ['tp5o']}, 11:35:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'talos_options': ['--disable-e10s', 11:35:25 INFO - '--xperf_path', 11:35:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:35:25 INFO - 'tests': ['tp5n']}, 11:35:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:35:25 INFO - 'talos_options': ['--xperf_path', 11:35:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:35:25 INFO - 'tests': ['tp5n']}}, 11:35:25 INFO - 'talos.zip': {'path': '', 11:35:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:35:25 INFO - Running post-action listener: _resource_record_post_action 11:35:25 INFO - ##### 11:35:25 INFO - ##### Running create-virtualenv step. 11:35:25 INFO - ##### 11:35:25 INFO - Running pre-action listener: _resource_record_pre_action 11:35:25 INFO - Running main action method: create_virtualenv 11:35:25 INFO - Creating virtualenv /builds/slave/test/build/venv 11:35:25 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 11:35:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:35:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:35:25 INFO - Using real prefix '/tools/python27' 11:35:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:35:26 INFO - Installing distribute.............................................................................................................................................................................................done. 11:35:28 INFO - Installing pip.................done. 11:35:28 INFO - Return code: 0 11:35:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:35:28 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')]} 11:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:28 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')]} 11:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:28 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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:28 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 11:35:28 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 11:35:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:28 INFO - 'HOME': '/Users/cltbld', 11:35:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:28 INFO - 'LOGNAME': 'cltbld', 11:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:28 INFO - 'MOZ_NO_REMOTE': '1', 11:35:28 INFO - 'NO_EM_RESTART': '1', 11:35:28 INFO - 'PAGER': '/bin/cat', 11:35:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:28 INFO - 'PWD': '/builds/slave/test', 11:35:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:28 INFO - 'SHELL': '/bin/bash', 11:35:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:28 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:28 INFO - 'USER': 'cltbld', 11:35:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:28 INFO - 'XPC_FLAGS': '0x0', 11:35:28 INFO - 'XPC_SERVICE_NAME': '0', 11:35:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:29 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 11:35:29 INFO - Cleaning up... 11:35:29 INFO - Return code: 0 11:35:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:35: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')]} 11:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35: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')]} 11:35:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:29 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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:29 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 11:35:29 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 11:35:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:29 INFO - 'HOME': '/Users/cltbld', 11:35:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:29 INFO - 'LOGNAME': 'cltbld', 11:35:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:29 INFO - 'MOZ_NO_REMOTE': '1', 11:35:29 INFO - 'NO_EM_RESTART': '1', 11:35:29 INFO - 'PAGER': '/bin/cat', 11:35:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:29 INFO - 'PWD': '/builds/slave/test', 11:35:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:29 INFO - 'SHELL': '/bin/bash', 11:35:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:29 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:29 INFO - 'USER': 'cltbld', 11:35:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:29 INFO - 'XPC_FLAGS': '0x0', 11:35:29 INFO - 'XPC_SERVICE_NAME': '0', 11:35:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:29 INFO - Downloading/unpacking psutil>=0.7.1 11:35:29 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 11:35:29 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 11:35:29 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 11:35:29 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 11:35:29 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 11:35:29 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 11:35:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:35:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:35:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:35:32 INFO - Installing collected packages: psutil 11:35:32 INFO - Running setup.py install for psutil 11:35:32 INFO - building 'psutil._psutil_osx' extension 11:35:32 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 11:35:32 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 11:35:33 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 11:35:33 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 11:35:33 INFO - building 'psutil._psutil_posix' extension 11:35:33 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 11:35:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:35:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:35:33 INFO - ^ 11:35:33 INFO - 1 warning generated. 11:35:33 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 11:35:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:35:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:35:33 INFO - Successfully installed psutil 11:35:33 INFO - Cleaning up... 11:35:33 INFO - Return code: 0 11:35:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:35:33 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')]} 11:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:33 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')]} 11:35:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:33 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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:33 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 11:35:33 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 11:35:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:33 INFO - 'HOME': '/Users/cltbld', 11:35:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:33 INFO - 'LOGNAME': 'cltbld', 11:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:33 INFO - 'MOZ_NO_REMOTE': '1', 11:35:33 INFO - 'NO_EM_RESTART': '1', 11:35:33 INFO - 'PAGER': '/bin/cat', 11:35:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:33 INFO - 'PWD': '/builds/slave/test', 11:35:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:33 INFO - 'SHELL': '/bin/bash', 11:35:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:33 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:33 INFO - 'USER': 'cltbld', 11:35:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:33 INFO - 'XPC_FLAGS': '0x0', 11:35:33 INFO - 'XPC_SERVICE_NAME': '0', 11:35:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:33 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 11:35:33 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 11:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:35:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:35:36 INFO - Installing collected packages: mozsystemmonitor 11:35:36 INFO - Running setup.py install for mozsystemmonitor 11:35:36 INFO - Successfully installed mozsystemmonitor 11:35:36 INFO - Cleaning up... 11:35:36 INFO - Return code: 0 11:35:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:35: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')]} 11:35:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35: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')]} 11:35:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:36 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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:36 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 11:35:36 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 11:35:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:36 INFO - 'HOME': '/Users/cltbld', 11:35:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:36 INFO - 'LOGNAME': 'cltbld', 11:35:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:36 INFO - 'MOZ_NO_REMOTE': '1', 11:35:36 INFO - 'NO_EM_RESTART': '1', 11:35:36 INFO - 'PAGER': '/bin/cat', 11:35:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:36 INFO - 'PWD': '/builds/slave/test', 11:35:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:36 INFO - 'SHELL': '/bin/bash', 11:35:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:36 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:36 INFO - 'USER': 'cltbld', 11:35:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:36 INFO - 'XPC_FLAGS': '0x0', 11:35:36 INFO - 'XPC_SERVICE_NAME': '0', 11:35:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:36 INFO - Downloading/unpacking blobuploader==1.2.4 11:35:36 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 11:35:36 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 11:35:36 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 11:35:36 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 11:35:36 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 11:35:36 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 11:35:39 INFO - Downloading blobuploader-1.2.4.tar.gz 11:35:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:35:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:35:39 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 11:35:39 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 11:35:39 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 11:35:39 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 11:35:39 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 11:35:39 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 11:35:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:35:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:35:39 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 11:35:39 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 11:35:39 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 11:35:39 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 11:35:39 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 11:35:39 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 11:35:40 INFO - Downloading docopt-0.6.1.tar.gz 11:35:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:35:40 INFO - Installing collected packages: blobuploader, requests, docopt 11:35:40 INFO - Running setup.py install for blobuploader 11:35:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:35:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:35:40 INFO - Running setup.py install for requests 11:35:41 INFO - Running setup.py install for docopt 11:35:41 INFO - Successfully installed blobuploader requests docopt 11:35:41 INFO - Cleaning up... 11:35:41 INFO - Return code: 0 11:35:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:41 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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:41 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 11:35:41 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 11:35:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:41 INFO - 'HOME': '/Users/cltbld', 11:35:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:41 INFO - 'LOGNAME': 'cltbld', 11:35:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:41 INFO - 'MOZ_NO_REMOTE': '1', 11:35:41 INFO - 'NO_EM_RESTART': '1', 11:35:41 INFO - 'PAGER': '/bin/cat', 11:35:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:41 INFO - 'PWD': '/builds/slave/test', 11:35:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:41 INFO - 'SHELL': '/bin/bash', 11:35:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:41 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:41 INFO - 'USER': 'cltbld', 11:35:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:41 INFO - 'XPC_FLAGS': '0x0', 11:35:41 INFO - 'XPC_SERVICE_NAME': '0', 11:35:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:35:41 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-xyV6Ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:35:41 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-mE6r1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:35:41 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-kzVc9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:35:41 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-kKxM9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-jyrDHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-bszKWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-0XXo29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-Fip2Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-CMPD_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-hAiLR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-jYQgI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-MLNsWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-H2KEpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:35:42 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-uXOd2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:43 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-9rGCNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:35:43 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-jnMFbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:35:43 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-HbHpIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:35:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:35:43 INFO - Running setup.py install for manifestparser 11:35:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:35:43 INFO - Running setup.py install for mozcrash 11:35:43 INFO - Running setup.py install for mozdebug 11:35:43 INFO - Running setup.py install for mozdevice 11:35:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:35:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:35:43 INFO - Running setup.py install for mozfile 11:35:44 INFO - Running setup.py install for mozhttpd 11:35:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Running setup.py install for mozinfo 11:35:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Running setup.py install for mozInstall 11:35:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Running setup.py install for mozleak 11:35:44 INFO - Running setup.py install for mozlog 11:35:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Running setup.py install for moznetwork 11:35:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:35:44 INFO - Running setup.py install for mozprocess 11:35:45 INFO - Running setup.py install for mozprofile 11:35:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:35:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:35:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:35:45 INFO - Running setup.py install for mozrunner 11:35:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:35:45 INFO - Running setup.py install for mozscreenshot 11:35:45 INFO - Running setup.py install for moztest 11:35:45 INFO - Running setup.py install for mozversion 11:35:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:35:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:35:45 INFO - Cleaning up... 11:35:45 INFO - Return code: 0 11:35:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:35:45 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')]} 11:35:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:45 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')]} 11:35:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:45 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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:45 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 11:35:45 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 11:35:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:45 INFO - 'HOME': '/Users/cltbld', 11:35:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:45 INFO - 'LOGNAME': 'cltbld', 11:35:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:45 INFO - 'MOZ_NO_REMOTE': '1', 11:35:45 INFO - 'NO_EM_RESTART': '1', 11:35:45 INFO - 'PAGER': '/bin/cat', 11:35:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:45 INFO - 'PWD': '/builds/slave/test', 11:35:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:45 INFO - 'SHELL': '/bin/bash', 11:35:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:45 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:45 INFO - 'USER': 'cltbld', 11:35:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:45 INFO - 'XPC_FLAGS': '0x0', 11:35:45 INFO - 'XPC_SERVICE_NAME': '0', 11:35:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-u0dyWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:35:46 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)) 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-3eWzDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:35:46 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)) 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-bpqn95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:35:46 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)) 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-6HOO2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:35:46 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)) 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-DlbAT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:35:46 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)) 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-ezyBAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:35:46 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)) 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-279wSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:35:46 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)) 11:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:35:46 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-yIqhUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-k8SR17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-gGmZCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-bCnZ6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-dCFTn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-lfVPLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-kbgC0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-MOC5_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-IFFh5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:35:47 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)) 11:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:35:47 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-Op3fIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:35:48 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)) 11:35:48 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)) 11:35:48 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)) 11:35:48 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)) 11:35:48 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)) 11:35:48 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)) 11:35:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:35:48 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 11:35:48 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 11:35:48 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 11:35:48 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 11:35:48 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 11:35:48 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 11:35:50 INFO - Downloading blessings-1.6.tar.gz 11:35:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:35:50 INFO - Installing collected packages: blessings 11:35:50 INFO - Running setup.py install for blessings 11:35:50 INFO - Successfully installed blessings 11:35:50 INFO - Cleaning up... 11:35:50 INFO - Return code: 0 11:35:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:35:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:35:51 INFO - Reading from file tmpfile_stdout 11:35:51 INFO - Current package versions: 11:35:51 INFO - blessings == 1.6 11:35:51 INFO - blobuploader == 1.2.4 11:35:51 INFO - docopt == 0.6.1 11:35:51 INFO - manifestparser == 1.1 11:35:51 INFO - mozInstall == 1.12 11:35:51 INFO - mozcrash == 0.17 11:35:51 INFO - mozdebug == 0.1 11:35:51 INFO - mozdevice == 0.48 11:35:51 INFO - mozfile == 1.2 11:35:51 INFO - mozhttpd == 0.7 11:35:51 INFO - mozinfo == 0.9 11:35:51 INFO - mozleak == 0.1 11:35:51 INFO - mozlog == 3.1 11:35:51 INFO - moznetwork == 0.27 11:35:51 INFO - mozprocess == 0.22 11:35:51 INFO - mozprofile == 0.28 11:35:51 INFO - mozrunner == 6.11 11:35:51 INFO - mozscreenshot == 0.1 11:35:51 INFO - mozsystemmonitor == 0.0 11:35:51 INFO - moztest == 0.7 11:35:51 INFO - mozversion == 1.4 11:35:51 INFO - psutil == 3.1.1 11:35:51 INFO - requests == 1.2.3 11:35:51 INFO - wsgiref == 0.1.2 11:35:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:35:51 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')]} 11:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:51 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')]} 11:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:51 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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:51 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 11:35:51 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 11:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:51 INFO - 'HOME': '/Users/cltbld', 11:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:51 INFO - 'LOGNAME': 'cltbld', 11:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:51 INFO - 'MOZ_NO_REMOTE': '1', 11:35:51 INFO - 'NO_EM_RESTART': '1', 11:35:51 INFO - 'PAGER': '/bin/cat', 11:35:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:51 INFO - 'PWD': '/builds/slave/test', 11:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:51 INFO - 'SHELL': '/bin/bash', 11:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:51 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:51 INFO - 'USER': 'cltbld', 11:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:51 INFO - 'XPC_FLAGS': '0x0', 11:35:51 INFO - 'XPC_SERVICE_NAME': '0', 11:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 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)) 11:35:51 INFO - Cleaning up... 11:35:51 INFO - Return code: 0 11:35:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:35:51 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')]} 11:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:51 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')]} 11:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:51 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 11:35:51 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 11:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:51 INFO - 'HOME': '/Users/cltbld', 11:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:51 INFO - 'LOGNAME': 'cltbld', 11:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:51 INFO - 'MOZ_NO_REMOTE': '1', 11:35:51 INFO - 'NO_EM_RESTART': '1', 11:35:51 INFO - 'PAGER': '/bin/cat', 11:35:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:51 INFO - 'PWD': '/builds/slave/test', 11:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:51 INFO - 'SHELL': '/bin/bash', 11:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:51 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:51 INFO - 'USER': 'cltbld', 11:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:51 INFO - 'XPC_FLAGS': '0x0', 11:35:51 INFO - 'XPC_SERVICE_NAME': '0', 11:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:51 INFO - Downloading/unpacking jsonschema 11:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:51 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 11:35:51 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 11:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:35:54 INFO - Downloading/unpacking functools32 (from jsonschema) 11:35: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 11:35: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 11:35: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 11:35: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 11:35: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 11:35: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 11:35:54 INFO - Downloading functools32-3.2.3-2.tar.gz 11:35:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:35:54 INFO - warning: no files found matching '*.txt' 11:35:54 INFO - no previously-included directories found matching 'build' 11:35:54 INFO - no previously-included directories found matching 'dist' 11:35:54 INFO - no previously-included directories found matching '.git*' 11:35:54 INFO - Installing collected packages: jsonschema, functools32 11:35:54 INFO - Running setup.py install for functools32 11:35:54 INFO - warning: no files found matching '*.txt' 11:35:54 INFO - no previously-included directories found matching 'build' 11:35:54 INFO - no previously-included directories found matching 'dist' 11:35:54 INFO - no previously-included directories found matching '.git*' 11:35:54 INFO - Successfully installed jsonschema functools32 11:35:54 INFO - Cleaning up... 11:35:54 INFO - Return code: 0 11:35:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:35:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:35:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:35:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:35:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ed47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed57420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb28a5afcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, '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.n2qUY2FDUZ/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.D001pJKNp4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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 11:35:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:35:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:35:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:35:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:35:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:35:54 INFO - 'HOME': '/Users/cltbld', 11:35:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:35:54 INFO - 'LOGNAME': 'cltbld', 11:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:54 INFO - 'MOZ_NO_REMOTE': '1', 11:35:54 INFO - 'NO_EM_RESTART': '1', 11:35:54 INFO - 'PAGER': '/bin/cat', 11:35:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:54 INFO - 'PWD': '/builds/slave/test', 11:35:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:35:54 INFO - 'SHELL': '/bin/bash', 11:35:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:35:54 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:35:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:35:54 INFO - 'USER': 'cltbld', 11:35:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:35:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:54 INFO - 'XPC_FLAGS': '0x0', 11:35:54 INFO - 'XPC_SERVICE_NAME': '0', 11:35:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:35:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:35:55 INFO - Downloading/unpacking flake8 11:35: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 11:35: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 11:35: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 11:35: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 11:35: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 11:35: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 11:35:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:35:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:57 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 11:35:57 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 11:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:35:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:57 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 11:35:57 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 11:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:58 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 11:35:58 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 11:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:35:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:35:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:35:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:35:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:35:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:35:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:35:58 INFO - no previously-included directories found matching 'docs/_build' 11:35:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:35:58 INFO - Running setup.py install for pep8 11:35:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:35:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:35:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:35:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:35:59 INFO - no previously-included directories found matching 'docs/_build' 11:35:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:35:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:35:59 INFO - Cleaning up... 11:35:59 INFO - Return code: 0 11:35:59 INFO - Running post-action listener: _resource_record_post_action 11:35:59 INFO - Running post-action listener: _start_resource_monitoring 11:35:59 INFO - Starting resource monitoring. 11:35:59 INFO - ##### 11:35:59 INFO - ##### Running install step. 11:35:59 INFO - ##### 11:35:59 INFO - Running pre-action listener: _resource_record_pre_action 11:35:59 INFO - Running main action method: install 11:35:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:35:59 INFO - Reading from file tmpfile_stdout 11:35:59 INFO - Detecting whether we're running mozinstall >=1.0... 11:35:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:35:59 INFO - Reading from file tmpfile_stdout 11:35:59 INFO - Output received: 11:35:59 INFO - Usage: mozinstall [options] installer 11:35:59 INFO - Options: 11:35:59 INFO - -h, --help show this help message and exit 11:35:59 INFO - -d DEST, --destination=DEST 11:35:59 INFO - Directory to install application into. [default: 11:35:59 INFO - "/builds/slave/test"] 11:35:59 INFO - --app=APP Application being installed. [default: firefox] 11:35:59 INFO - mkdir: /builds/slave/test/build/application 11:35:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 11:36:31 INFO - Reading from file tmpfile_stdout 11:36:31 INFO - Output received: 11:36:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:36:31 INFO - Running post-action listener: _resource_record_post_action 11:36:31 INFO - ##### 11:36:31 INFO - ##### Running run-tests step. 11:36:31 INFO - ##### 11:36:31 INFO - Running pre-action listener: _resource_record_pre_action 11:36:31 INFO - Running main action method: run_tests 11:36:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:36:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:36:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:36:31 INFO - 2016-05-31 11:36:31.980 screenresolution[7322:126499] starting screenresolution argv=screenresolution get 11:36:31 INFO - 2016-05-31 11:36:31.983 screenresolution[7322:126499] Display 0: 1600x1200x32@60 11:36:31 INFO - 2016-05-31 11:36:31.988 screenresolution[7323:126502] starting screenresolution argv=screenresolution list 11:36:31 INFO - Available Modes on Display 0 11:36:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:36:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:36:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:36:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:36:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:36:32 INFO - Intel Iris: 11:36:32 INFO - Chipset Model: Intel Iris 11:36:32 INFO - Type: GPU 11:36:32 INFO - Bus: Built-In 11:36:32 INFO - VRAM (Dynamic, Max): 1536 MB 11:36:32 INFO - Vendor: Intel (0x8086) 11:36:32 INFO - Device ID: 0x0a2e 11:36:32 INFO - Revision ID: 0x0009 11:36:32 INFO - Displays: 11:36:32 INFO - Display: 11:36:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:36:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:36:32 INFO - Display Serial Number: 1600x1200 60 11:36:32 INFO - Main Display: Yes 11:36:32 INFO - Mirror: Off 11:36:32 INFO - Online: Yes 11:36:32 INFO - Rotation: Supported 11:36:32 INFO - Return code: 0 11:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:36:32 INFO - Python 2.7.3 11:36:32 INFO - Return code: 0 11:36:32 INFO - grabbing minidump binary from tooltool 11:36:32 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')]} 11:36:32 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 0x10ebe6e00>, '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 0x10ea4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:36:32 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 11:36:32 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 11:36:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:36:32 INFO - Return code: 0 11:36:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:36:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:36:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:36:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:36:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:36:33 INFO - Return code: 0 11:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0234', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0234 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:36:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n2qUY2FDUZ/Render', 11:36:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:33 INFO - 'HOME': '/Users/cltbld', 11:36:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:33 INFO - 'LOGNAME': 'cltbld', 11:36:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:36:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:33 INFO - 'MOZ_NO_REMOTE': '1', 11:36:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:36:33 INFO - 'NO_EM_RESTART': '1', 11:36:33 INFO - 'PAGER': '/bin/cat', 11:36:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:33 INFO - 'PWD': '/builds/slave/test', 11:36:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:36:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:33 INFO - 'SHELL': '/bin/bash', 11:36:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D001pJKNp4/Listeners', 11:36:33 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 11:36:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:33 INFO - 'USER': 'cltbld', 11:36:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:33 INFO - 'XPC_FLAGS': '0x0', 11:36:33 INFO - 'XPC_SERVICE_NAME': '0', 11:36:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0234', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:36:33 INFO - mozversion application_buildid: 20160531103942 11:36:33 INFO - mozversion application_changeset: 9eebb2835273857468a80e0e89013673352a37e7 11:36:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:36:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:36:33 INFO - mozversion application_name: Firefox 11:36:33 INFO - mozversion application_remotingname: firefox-dev 11:36:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:36:33 INFO - mozversion application_vendor: Mozilla 11:36:33 INFO - mozversion application_version: 48.0a2 11:36:33 INFO - mozversion platform_buildid: 20160531103942 11:36:33 INFO - mozversion platform_changeset: 9eebb2835273857468a80e0e89013673352a37e7 11:36:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:36:33 INFO - mozversion platform_version: 48.0a2 11:36:33 INFO - using testdate: 1464719793 11:36:33 INFO - actual date: 1464719793 11:36:33 INFO - starting webserver on 'localhost:57913' 11:36:33 INFO - SUITE-START | Running 2 tests 11:36:33 INFO - TEST-START | dromaeo_css 11:36:33 INFO - operating with platform_type : mac_ 11:36:33 INFO - Initialising browser for dromaeo_css test... 11:36:33 INFO - TEST-INFO | started process 7341 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpukZR2I/profile http://localhost:57913/getInfo.html) 11:36:35 INFO - PROCESS | 7341 | __metrics Screen width/height:1600/1200 11:36:35 INFO - PROCESS | 7341 | colorDepth:24 11:36:35 INFO - PROCESS | 7341 | Browser inner width/height: 1024/689 11:36:35 INFO - PROCESS | 7341 | __metrics 11:36:36 INFO - PROCESS | 7341 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:36:36 ERROR - PROCESS | 7341 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:36:36 INFO - TEST-INFO | 7341: exit 0 11:36:36 INFO - Browser initialized. 11:36:36 INFO - Running cycle 1/1 for dromaeo_css test... 11:36:36 INFO - TEST-INFO | started process 7343 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpukZR2I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:36:48 INFO - PROCESS | 7343 | RSS: Main: 211128320 11:36:48 INFO - PROCESS | 7343 | 11:37:39 INFO - PROCESS | 7343 | 1464719859984 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] 11:39:49 INFO - PROCESS | 7343 | Cycle 1(1): loaded http://localhost:57913/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57913/tests/dromaeo/cssquery-ext.html) 11:39:52 INFO - PROCESS | 7343 | RSS: Main: 410312704 11:39:52 INFO - PROCESS | 7343 | 11:42:55 INFO - PROCESS | 7343 | Cycle 1(1): loaded http://localhost:57913/tests/dromaeo/cssquery-ext.html (next: http://localhost:57913/tests/dromaeo/cssquery-jquery.html) 11:42:56 INFO - PROCESS | 7343 | RSS: Main: 353726464 11:42:56 INFO - PROCESS | 7343 | 11:45:58 INFO - PROCESS | 7343 | Cycle 1(1): loaded http://localhost:57913/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57913/tests/dromaeo/cssquery-mootools.html) 11:45:58 INFO - PROCESS | 7343 | RSS: Main: 355041280 11:45:58 INFO - PROCESS | 7343 | 11:49:00 INFO - PROCESS | 7343 | Cycle 1(1): loaded http://localhost:57913/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57913/tests/dromaeo/cssquery-prototype.html) 11:49:01 INFO - PROCESS | 7343 | RSS: Main: 355012608 11:49:01 INFO - PROCESS | 7343 | 11:52:04 INFO - PROCESS | 7343 | Cycle 1(1): loaded http://localhost:57913/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57913/tests/dromaeo/cssquery-yui.html) 11:52:05 INFO - PROCESS | 7343 | RSS: Main: 322793472 11:52:05 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | Cycle 1(1): loaded http://localhost:57913/tests/dromaeo/cssquery-yui.html (next: http://localhost:57913/tests/dromaeo/cssquery-dojo.html) 11:55:08 INFO - PROCESS | 7343 | RSS: Main: 364486656 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | __start_tp_report 11:55:08 INFO - PROCESS | 7343 | _x_x_mozilla_page_load 11:55:08 INFO - PROCESS | 7343 | _x_x_mozilla_page_load_details 11:55:08 INFO - PROCESS | 7343 | |i|pagename|runs| 11:55:08 INFO - PROCESS | 7343 | |0;dojo.html;3280.753943454241;3365.9999999999986;3382.492626106083;3383.678550537698;3447.3450044491547;5600.6079574429805;5611.466910643492;5627.6060675752715;5645.209670646108;5656.632318899268;5619.775208991636;5661.065924122515;5667.319921609403;5675.971620141904;5678.744456499458;4318.330658747899;5112.590992720573;5140.845774626767;5164.173732202839;5223.555997740196;5211.86970325743;5259.13224317989;5260.92108618371;5269.262303277545;5275.340582427197;5146.819861304855;5183;5214.921776173361;5217.791288348461;5243.030039442696;5213.1919552469235;5229.267902493654;5245.973770131163;5258.8685282867855;5288.418273989858;4251.957480425187;5065.721385323805;5113.795448182105;5137.40919794221;5190.377154741455;5492.258545096401;5542.418046105182;5543.362513310981;5572.7770889164385;5614.9893019256515;6179.876402471926;6216.378362163793;6225.844353891143;6226.59527130735;6228.564000519966;5151.381834179885;5213.687178769248;6179.84550386244;6188.133661287423;6276.121343011982;5724.914126288109;5734.827955161351;5768.394318596487;5785.82642520725;5793.188953546507;6278.089676996809;6281.96859015702;6294.339094395023;6298.937010629927;6311.652859092794;6019.909701354483;6098.573099882966;6110.663913484801;6126.479249263773;6140.293866205444;6162.876742465126;6228.813135605939;6253;6273.560850740404;6278.811635650938;6126.908096378558;6169.96915015422;6187.257529096537;6189.381061893865;6213.161223234805;6102.786402475892;6159.5072394208355;6206.131141640174;6219.751209951552;6233.0338797486465;4808.869915569839;4873.756312184377;4907.877303067452;4920.261960705922;4937.901241975141;4400.361947517592;4413.691041627056;4433.7561434121435;4434.290513517822;4458.063806600585;5502.5597952163735;5613.943860561342;5688.857778555486;5714.142878568165;5723;6156.815295541141;6235.563510554309;6269.059641053787;6273.52948528853;6283.8114856554375;4863.562279394871;5669.6314739541795;5676.8864622707315;5695.943040569624;5700.287464066992;5275.63070585063;5366.8658283542445;5413.539849112791;5419.187121931663;5419.187121931663;6249.375062493714;6270.717817698214;6315.873682526416;6323.747050118128;6337.112804207507;6230.626162430088;6260.280067792262;6280.623162610258;6284.4972402206795;6292.1505596743855;6202.751889924533;6328.588641738273;6338.746450141943;6343.175387199634;6359.618422894641;6072.544559158169;6109.2974307955155;6118.13190915367;6123.831729140362;6126.487138464006;6127.8615298716295;6254.655993920378;6254.8123556293385;6255.280642726145;6264.185655864709;5914;6052.576319657582;6140.692965351804;6145.324014358506;6157.168782214344;5859.326177489644;5990.071538911476;6017.578769486094;6148.95467770489;6163.599366041193;4834.6374021947795;5948.970255148696;5961.612495187799;5973.731182096729;5998.610090344114;5891.764329426775;5953.672548009901;6055.939440605537;6091.933911565547;6102.481289090477;5790.1893734877995;5847.619904706181;5897.5576831736935;5912.172295878494;5913.201717768046 11:55:08 INFO - PROCESS | 7343 | |1;ext.html;480.93266942628014;492.77086154937945;500.65204682745497;509.9923501147483;521.9138842091055;38645.61354386455;39615.60384396159;39976.99999999996;40050;40091;43859;44083.55916440843;44236;44326.77836610821;44419;8583.82832343356;8699.739007829776;9595.08846659568;9820.214382849355;9839.311248212627;8656.567171641424;11454.885451145507;11689.941550292237;11731.120165987542;11733.002694770954;11652.533898644044;11724.531018759239;11732.589359372423;11759.294442333445;11761.823572646417;8618.870716939187;11460.828087578693;11699.23954942926;11738.765224695544;11819.586314478953;11464.426778661034;11597.594084207096;11605.955464008195;11610.825837612443;11615.245009074382;33037.50443743346;41704.1659166818;41766.58233417657;41840.790796045825;41863.58136418627;3219.3078488125166;3293.571835661349;3378.2230087079865;3383.374075795961;3565.893023209308;28872.42255154885;29184.41631167365;29238;29337.41325173506;30072.398552029055;2237.8097861681777;2685.0333879803256;2689.8789554470095;2756.724327567247;2758.600002999574;478902.421951559;481256;484181;484462;484830.57584712206;62575.37424625696;63619;63631.68184159049;63688.68155659192;63796.36203637997;69517;71161;71231;71288.2871171292;71313;17388.130593470276;17462.388816391365;17574.209160587805;17596.648067038586;17599.73600395995;12558.874411255953;12769.233845969271;13331.466741330432;13446.126001809851;13519;19541.51412068687;20288.492787680425;20339.08474118668;20402.48993775138;20405.387838364575;7673.386129109658;7728.149903510609;7741.225877412214;7770.572618506037;7777.92222077772;922.2115091596722;936.9484678342757;975.0639386189338;1066.7119877633152;1095.1239008792934;30199.396012079636;30406.08781736507;30742.846285768428;30762;30856.53715194274;1076.8438576406534;1089.6814854026559;1137.2323681514945;1241.4103300931986;1274.5411651805343;15416.922915385352;15508;15779.526614201592;15808.227732046147;15859.682806343808;598084;602936;602988;603695;605463;59228;59764;60646.393536064075;60695.08957365643;60779.39220607738;1361.360160724458;1387.500499820064;1411.56947131126;1417.5959851442497;1511.894167408271;23223.76776232216;29329.85335073311;31520;31539.684603154594;31553.368932621677;52795.736021319644;53190.73404632952;53320.46679533155;53325.73337133289;53355.466445336606;372173.1391343026;380511;381788;383325;384061;24006.87996560006;24436.14473493419;26890.59664105079;27264.59103113415;27653;42480.17678408958;42530.14939701251;42973.570264296955;43557.3466397998;43829.780851095544;34665.306693866485;34933;36148;36151.63848361483;36722.4491632631;35358.823205883804;35551.82224088863;35660.46509302311;36427;36448.63551364558;36565;37252;37534.6246537542;37622.8118859404;37850.0537486565;31586.368272633958;33002.66997329996;33174.17064573405;33196.668033320326;33234.501482477295;24284.51430971406;24755.62866556967;24842.875785620956;25221.74778252194;25265 11:55:08 INFO - PROCESS | 7343 | |2;jquery.html;327.29631292720654;395.1957765946299;396.5162501747868;399.60638770810766;404.4883222723254;19269.614607707837;20461.283855065074;21265.14939402424;21363.145474181016;21384.679229811565;21151.788482115175;21166.153353865866;21477.463063423424;21912.45218869525;22566.661500077473;5770.422957704217;5897.351291357947;6013.819585412444;6116.113163591277;6117.082437634368;5961.195238642771;5978.192943952576;6020.45815876571;6024.186734790791;6034.547408944325;4945.950540494603;5941.7029148542615;5975.850603734898;5981.431763982415;5994.8801023979495;5854.473097421232;5863.120531920204;5910.586258961875;5911.793087241969;5918.2898052234;5119.744012799345;5196.68819870809;5202;5210.635255532115;5220.530152286313;26330.21009369722;26331;26723.93104275846;26728.797204125665;26783.86608066947;2003.8497112716605;2769.9307517312027;2931.4723349797027;2944.440556294297;2949.2626843289177;9888.505574721235;10014.649487267983;10033.648822291185;10066.094051535323;10249.538770755335;1693.7036018696679;1726.2059452651795;1738.1048759888668;1765.62921786425;1789.33794496036;58835.11747323794;64518;104656.47671761592;105506;108292.4585377076;11747.29516229029;11778.88221117795;11805.173637845268;11812.940935295268;12056.63830085099;15174.696506069817;15189.468368607046;15191.480851914721;15219.771703424458;15230.162341071347;7927;11184.217104802749;11248.662540123809;11641.59254426091;11647.53409863613;9408.858867116998;9557.665481708107;9564.95217523908;9727.805443891224;9782.315237933277;8273.007239131222;9615.278854085971;9879.50602469888;9977.650782222732;9982.401055936667;3433.296174284266;3479.5998460176897;3722.4788529605876;3751.7748935063983;3900.395438707005;880.5729221327651;1022.7801022780138;1049.3965969567378;1051.264115119404;1067.2315932528568;20411.979401030185;20889.79110208909;21129;21156.894215529133;21312.57374852494;935.1817159985013;962.6967505235829;981.3228872078244;989.2926557511414;1006.3609607898891;10317.884833609092;11047.392393418439;11217.887821121847;11286.943565282121;11308.378039207922;585896;593555;608207;611411;615164;59840.40159598435;69713;111709.44145279384;113080;113326;857.7341024282492;1002.7142264454741;1015.2994433840627;1015.4567306490992;1016.3596933931576;17854.107294634956;17985.550361241072;18089.36687215941;18292.35976740861;18808.965506896704;50795.74602126966;50857.47427577178;50910.490895090574;51787.48212517826;52362.73818630882;67963.66018169878;68486.65756671184;69428.6528567354;70736.29263707504;73248;7996.800079998046;8040.87938680932;8156.143604921398;8254.009518857536;8272.131426200403;9081.046490118706;11679.299242045505;12210.511579536718;12352.876471235173;12474.313912734886;10724.088452481627;10931.890681093088;10997.285176463503;11012.614558490415;11110.333379997226;6612.866174605167;6628.966855165693;7356.337929586363;7414.406847452299;8103.473274237156;7867.095284042409;8001.559914204775;8050.758477245692;8080.474769139987;8378.287845532954;11511.366874821964;11642.592509262135;12011.279323240633;12205;12290.508379664714;10782.137429005817;11246.437678116225;11307.547698091985;11472;11525.193236473702 11:55:08 INFO - PROCESS | 7343 | |3;mootools.html;1407;1551.123615157436;1586.595418168367;1807.7198034304688;1952.959157680225;7981.441299109065;8633;8798.780030499242;8815.426997245182;8826.426282291646;8500.447470914387;8731.825363492726;8752.824943501126;8758.91241087589;8764.91235087649;2461.4338702098485;2487.4278915849277;2488.3032750829707;2489.390099425634;2494.762997515233;2425.6967879015124;2474.2948259745976;2479.0579579759665;2481.5161043596504;2487.676602041732;2401.543706695731;2416.8066554675584;2418.7823095921362;2420.782129608335;2425.2966639674473;2411.240459255329;2447.49826285611;2448.4001419652122;2452.8037756979397;2458.729539750626;2409.891554880017;2440.865752383618;2456.0666946560373;2460.274219105368;2460.4341001569737;8553.101924297984;8815;8845.513496757676;8848.026717061119;8848.336374771854;5388.811391601275;5504.532114770249;5544.556435485151;5548.4174161712845;5556.749946252428;2977.3598676284487;3220.4364236258793;3283.655216202312;3320.3193345364143;3555.3600351936643;1072.0458791675414;1623.3344328825242;1654.5119189839027;1670.6157583755682;1706.2066139245187;11446.656600301922;11666.416679166092;11674.41627918609;11686.590969316117;11709.765804683859;4537.795799189044;4544.409226800561;4630.930536041962;4646.930296045562;4669.486356500782;9314.673986410444;9322.487263200454;9330.766730831783;9341.11259430353;9344.579493922789;5345.893082138336;5350.357957045102;5359.732013399314;5360.249565060894;5376.462353764592;4171.10321280926;4316.309390497506;4398.856297362709;4886.486918873538;4965.0566392385335;3825.885223443301;3841.5390153181797;3886.2227554489214;3912.6478616924614;3932.803359831997;1050.3540322701483;1190.9166358354832;1208.3897631695938;1219.9817002744967;1220.4263995921901;648.6918713610997;832.7918020494876;902.963881444748;945.7871978804851;952.3143336797494;5738.196652468658;5749.7700091996685;5798.710064496759;5944;6031.698415079316;800.6109400190664;959.4579062829479;994.3586386780486;995.5370752600076;1015.4719545836124;3805.1438426354403;3913.099987002948;3959.920801583952;3963.742356746802;4042.130941847517;10377.27359084872;10798.082163016075;10872.347659140476;10875.564977400974;10891.455427228608;10627.937206279465;10734.57061717538;10743.892561074446;10744.247902646895;10841.349519028883;8364.330853531703;8413.70552030676;8428.283595894418;8433.241008309158;8476.618552165168;5398.64908780928;5765.97117014429;5814.418558144152;5896.174535564938;5944.456053414523;9510.38182518134;9535.189508891823;9547.140757331874;9697.078777516124;9720.319577629498;9473.4315941041;9847.261455390731;9859.408435493893;9865.605375784888;10165.13596344319;6992.510524263253;7007.264237255014;7082.4688148390105;7084.64576771149;7085.220625731063;7846.646900889359;8106.47307924983;8162.632681529227;8345.499270043816;8448.746537603882;7357.926420735724;7410.703571857065;7460.365868900987;7485.288897554724;7599.886001709868;7352.48532602734;7378.372838308804;7409.555426674417;7485.326320631169;7551.509151905108;6722.865542689021;6968.198657154289;6972.7908162753165;7039.190493093155;7042.366187043191;4860.17377045896;4958.008398320422;5006.774695138654;5069.670471419346;5117.1300878851425;5004.799808007784;5069.518395752398;5072.188449848154;5105.33630628016;5274.12976858814 11:55:08 INFO - PROCESS | 7343 | |4;prototype.html;305.12581454133897;319.23861590107595;326.7337120247;329.14586647649344;333.386568713567;6568.802935911925;6688.264290927994;6709.66451677416;6719.361660642237;6724.630145342005;5276.498732620405;6619.900701489481;6620.569662971916;6624.966875165629;6652.767153149641;3957.990712368342;4683.648726345529;4765.999140180566;4787.042591481701;4787.353707249529;4780.617550595944;4846.830360937368;4860.562549370574;4893.4617192108835;4954.504549545053;4430.803683005586;4572.268437050073;4766.73782941938;4835.323054772335;4857.781399837016;3909.7263191576603;4932.210846264598;4935.210366341368;4936.308916751622;4938.38270216223;3280.327532855759;4009.1781184857327;4153.1486045360625;4163.708540402173;4221.957780422187;6356.459700925427;6384.138141350904;6396.552241343108;6403.807885763435;6429.324920883333;5825.88348233033;5830.0380437227805;5830.854228644275;5831.154482600042;5834.854128646775;6998.405135563483;7035.929640703579;7037.648117594151;7045.154581450259;7052.682629281643;4690.577847993697;4700.2009658358165;4701.623870090419;4711.128441238382;4712.528747125294;7217.7473788417155;7527.209642987462;7556.2443755624545;7562.546247225184;7567.508111972759;3884.378499440076;4531.342955271467;4638.744869032235;4644.140833945663;4647.34937108805;4720.0087981523575;4726.172919739032;4728;4731.030138821533;4741.596964258008;5334.306540149756;5626.818368142736;5654.293213348332;5663.518600918886;5673.375928647845;6267.655278959701;6867.587944723333;7139;7148.213696493382;7182.3535881770895;1374.3609221711952;1615.935362585507;1623.091069001363;1637.9426720064741;1651.8100418452032;882.3911501064246;1025.4821315235906;1028.984565231522;1048.0567489259574;1048.8793788714243;6438.420542151229;6704.7988560343265;6705.832354191184;6706.524564595781;6717.764878229238;5918.289805223315;7217.783466496014;7318.707251709979;7325.560466372035;7336.193018767931;6017.157597936292;6589.86820263592;6716.764913228111;6752.155980502437;6772.288909664513;3884.514435695538;3931.233409485123;3944.3097457944746;3945.940810887839;3955.3275943089743;7513.1359893612935;7818.452708310478;7824.92175078242;7828.765137045898;7838.294553490213;6915.688794004182;7598.164201937782;7610.391168706601;7625.504342217738;7633.694652213961;6274.874502509924;6917.169939607279;7836.882446763303;7841.882371764542;7844.17636148219;7452.478326517092;7453.217412171643;7482.401407887465;7484.775456736306;7489.662965166472;6978.755743548952;7223.494355395072;7334.193238743627;7334.96332518334;7354.043974283309;7728.420368472274;7759.068911730403;7798.610069496616;7832.608369581612;7845.882311765214;4819.49395313487;4895.216765317532;4896.510348965075;4901.14230009754;4902.509749025069;6215.508278013154;7015.193252775996;7321.63391830417;7328.2305357938785;7330.743423980135;6872.725090996304;7141.143062832286;7143.464240181903;7145.714171433085;7203.315685010125;7115.53749006313;7161.39128174111;7182.640867956686;7184.317489838457;7185.065941427581;6983;6997.510174287751;6999.545029573062;7010.824729381602;7024.367806897404;7162.355388015104;7165.068541089625;7177.892331614926;7187.353138217585;7224.819379515344;6712.241144958839;7003.194632617314;7005.719771209298;7013.544119632207;7023.543469674455 11:55:08 INFO - PROCESS | 7343 | |5;yui.html;701.7543859649122;751.1174370115114;756.4666909828571;762.4319881688143;813.1583810755869;14747.926260368697;14749;15105.697886042273;15115.39538418462;15126.092434453927;13619.25094119823;13690.794638080437;13693;13710.794338084937;13723.97070219735;731.2869951797009;738.1474397071364;871.4509858788986;889.8576227803569;904.055262250946;889.5196593839319;898.7258886039755;900.8243392538458;902.3277658144691;902.7923577577161;938.6667732954805;951.9286053545977;952.8284908716428;953.6423841059602;954.2080073538981;745.124478737484;934.9298802589833;941.4351389166513;959.3045042344314;963.0129117654391;704.7815177295055;709.765777293492;713.3437237741291;713.425692317684;713.4399496395305;14649.414023439049;14749;14759.040662356912;14770.704585908328;14775;12431.12982091254;12574.81137782934;12741.36293185337;12862.549810756578;12963.48146074156;10002.149817265541;10031.849522257173;10078.244131690079;10161.18710503158;10257;3499.65003499648;3567.8572857085687;3569.607343192247;3648.1791596890753;3657.963420365789;82787.5860620693;82898.58550707207;83040;83235;83508.58245708793;9347.158755712018;9366.718998430058;9419.576119074658;9485.810283794286;9632.662856799978;17182;21187.68218476724;21906.12375504962;22072.88963555204;22095.889520552617;7508.924910750822;7549.320561149523;7802.921970780219;7908.209179082046;7994.68021279154;4088.1619268049976;4119.958800411958;4158.9376159357635;4185.351270553069;4332.090261045153;3574.803385813805;3633.781973081571;3641.198936234024;3650.762700424464;3669.4495825625836;554.5508138407822;643.1446176585131;660.2704012066597;670.1321788284183;682.8224661587924;535.3736128729396;541.9105847535192;572.5391060196582;579.4524174654995;625.2028663454041;7665.348445382094;7988.960055199686;8138.674453021813;8159.143289954469;8176.3458923286;559.815260963881;565.5277843001057;568.4372471253429;599.9010163323003;616.8735409241095;3749.5875453700073;3855.5373355197557;3904.5314562252142;3959.544652364957;4050.5544390117066;88472;90642.1871562565;92234;92531.53734231286;92768;73551;73809.63095184597;76692;77041;77796.83304750433;27902.72097279001;50750;68115;68423.63152736917;68906.9663955031;7668.961655191688;7826.178251283533;7964.721234756756;7968.960155199187;8097.71657991991;31987;40425.59574404336;41818.53635122878;47364.52635473601;47469.762651186524;63875.68062159659;63907.4023149425;64787.35212647813;65056.67471662611;65276;9819.410835349901;9916.305858590118;9970.30207885441;10138.03688649577;10679.412632305297;8692.652293908173;11050.944745276543;11186.720331991766;11206;12108.697282568006;10236.692899213036;10433.32183408076;10448.216383771096;10543.57825686964;10751.301165424222;10448.68653940383;10515.316504427188;10525.473726313807;10676.93230676926;10687.358758474516;10216.693499195037;10395.844062339222;10459.058684718411;10524.158067354747;10611.257211995086;7362.484626076123;8903.777405564913;8928;9180.908190918006;9399.154076133182;8919.50253928901;8959.372843900865;8969.865452018355;9008.594613242289;9145.5884485197 11:55:08 INFO - PROCESS | 7343 | __end_tp_report 11:55:08 INFO - PROCESS | 7343 | __start_cc_report 11:55:08 INFO - PROCESS | 7343 | _x_x_mozilla_cycle_collect,2162 11:55:08 INFO - PROCESS | 7343 | __end_cc_report 11:55:08 INFO - PROCESS | 7343 | __startTimestamp1464720908272__endTimestamp 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | ------- Summary: start ------- 11:55:08 INFO - PROCESS | 7343 | Number of tests: 6 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | [#0] dojo.html Cycles:165 Average:5688.52 Median:5897.56 stddev:657.77 (11.2%) stddev-sans-first:632.08 11:55:08 INFO - PROCESS | 7343 | Values: 3280.8 3366.0 3382.5 3383.7 3447.3 5600.6 5611.5 5627.6 5645.2 5656.6 5619.8 5661.1 5667.3 5676.0 5678.7 4318.3 5112.6 5140.8 5164.2 5223.6 5211.9 5259.1 5260.9 5269.3 5275.3 5146.8 5183.0 5214.9 5217.8 5243.0 5213.2 5229.3 5246.0 5258.9 5288.4 4252.0 5065.7 5113.8 5137.4 5190.4 5492.3 5542.4 5543.4 5572.8 5615.0 6179.9 6216.4 6225.8 6226.6 6228.6 5151.4 5213.7 6179.8 6188.1 6276.1 5724.9 5734.8 5768.4 5785.8 5793.2 6278.1 6282.0 6294.3 6298.9 6311.7 6019.9 6098.6 6110.7 6126.5 6140.3 6162.9 6228.8 6253.0 6273.6 6278.8 6126.9 6170.0 6187.3 6189.4 6213.2 6102.8 6159.5 6206.1 6219.8 6233.0 4808.9 4873.8 4907.9 4920.3 4937.9 4400.4 4413.7 4433.8 4434.3 4458.1 5502.6 5613.9 5688.9 5714.1 5723.0 6156.8 6235.6 6269.1 6273.5 6283.8 4863.6 5669.6 5676.9 5695.9 5700.3 5275.6 5366.9 5413.5 5419.2 5419.2 6249.4 6270.7 6315.9 6323.7 6337.1 6230.6 6260.3 6280.6 6284.5 6292.2 6202.8 6328.6 6338.7 6343.2 6359.6 6072.5 6109.3 6118.1 6123.8 6126.5 6127.9 6254.7 6254.8 6255.3 6264.2 5914.0 6052.6 6140.7 6145.3 6157.2 5859.3 5990.1 6017.6 6149.0 6163.6 4834.6 5949.0 5961.6 5973.7 5998.6 5891.8 5953.7 6055.9 6091.9 6102.5 5790.2 5847.6 5897.6 5912.2 5913.2 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | [#1] ext.html Cycles:180 Average:63935.24 Median:34799.15 stddev:132350.10 (380.3%) stddev-sans-first:132635.62 11:55:08 INFO - PROCESS | 7343 | Values: 480.9 492.8 500.7 510.0 521.9 38645.6 39615.6 39977.0 40050.0 40091.0 43859.0 44083.6 44236.0 44326.8 44419.0 8583.8 8699.7 9595.1 9820.2 9839.3 8656.6 11454.9 11689.9 11731.1 11733.0 11652.5 11724.5 11732.6 11759.3 11761.8 8618.9 11460.8 11699.2 11738.8 11819.6 11464.4 11597.6 11606.0 11610.8 11615.2 33037.5 41704.2 41766.6 41840.8 41863.6 3219.3 3293.6 3378.2 3383.4 3565.9 28872.4 29184.4 29238.0 29337.4 30072.4 2237.8 2685.0 2689.9 2756.7 2758.6 478902.4 481256.0 484181.0 484462.0 484830.6 62575.4 63619.0 63631.7 63688.7 63796.4 69517.0 71161.0 71231.0 71288.3 71313.0 17388.1 17462.4 17574.2 17596.6 17599.7 12558.9 12769.2 13331.5 13446.1 13519.0 19541.5 20288.5 20339.1 20402.5 20405.4 7673.4 7728.1 7741.2 7770.6 7777.9 922.2 936.9 975.1 1066.7 1095.1 30199.4 30406.1 30742.8 30762.0 30856.5 1076.8 1089.7 1137.2 1241.4 1274.5 15416.9 15508.0 15779.5 15808.2 15859.7 598084.0 602936.0 602988.0 603695.0 605463.0 59228.0 59764.0 60646.4 60695.1 60779.4 1361.4 1387.5 1411.6 1417.6 1511.9 23223.8 29329.9 31520.0 31539.7 31553.4 52795.7 53190.7 53320.5 53325.7 53355.5 372173.1 380511.0 381788.0 383325.0 384061.0 24006.9 24436.1 26890.6 27264.6 27653.0 42480.2 42530.1 42973.6 43557.3 43829.8 34665.3 34933.0 36148.0 36151.6 36722.4 35358.8 35551.8 35660.5 36427.0 36448.6 36565.0 37252.0 37534.6 37622.8 37850.1 31586.4 33002.7 33174.2 33196.7 33234.5 24284.5 24755.6 24842.9 25221.7 25265.0 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | [#2] jquery.html Cycles:180 Average:33380.72 Median:2946.85 stddev:99263.47 (3368.5%) stddev-sans-first:99510.90 11:55:08 INFO - PROCESS | 7343 | Values: 327.3 395.2 396.5 399.6 404.5 19269.6 20461.3 21265.1 21363.1 21384.7 21151.8 21166.2 21477.5 21912.5 22566.7 5770.4 5897.4 6013.8 6116.1 6117.1 5961.2 5978.2 6020.5 6024.2 6034.5 4946.0 5941.7 5975.9 5981.4 5994.9 5854.5 5863.1 5910.6 5911.8 5918.3 5119.7 5196.7 5202.0 5210.6 5220.5 26330.2 26331.0 26723.9 26728.8 26783.9 2003.8 2769.9 2931.5 2944.4 2949.3 9888.5 10014.6 10033.6 10066.1 10249.5 1693.7 1726.2 1738.1 1765.6 1789.3 58835.1 64518.0 104656.5 105506.0 108292.5 11747.3 11778.9 11805.2 11812.9 12056.6 15174.7 15189.5 15191.5 15219.8 15230.2 7927.0 11184.2 11248.7 11641.6 11647.5 9408.9 9557.7 9565.0 9727.8 9782.3 8273.0 9615.3 9879.5 9977.7 9982.4 3433.3 3479.6 3722.5 3751.8 3900.4 880.6 1022.8 1049.4 1051.3 1067.2 20412.0 20889.8 21129.0 21156.9 21312.6 935.2 962.7 981.3 989.3 1006.4 10317.9 11047.4 11217.9 11286.9 11308.4 585896.0 593555.0 608207.0 611411.0 615164.0 59840.4 69713.0 111709.4 113080.0 113326.0 857.7 1002.7 1015.3 1015.5 1016.4 17854.1 17985.6 18089.4 18292.4 18809.0 50795.7 50857.5 50910.5 51787.5 52362.7 67963.7 68486.7 69428.7 70736.3 73248.0 7996.8 8040.9 8156.1 8254.0 8272.1 9081.0 11679.3 12210.5 12352.9 12474.3 10724.1 10931.9 10997.3 11012.6 11110.3 6612.9 6629.0 7356.3 7414.4 8103.5 7867.1 8001.6 8050.8 8080.5 8378.3 11511.4 11642.6 12011.3 12205.0 12290.5 10782.1 11246.4 11307.5 11472.0 11525.2 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | [#3] mootools.html Cycles:180 Average:5688.81 Median:5195.63 stddev:3131.94 (60.3%) stddev-sans-first:3124.20 11:55:08 INFO - PROCESS | 7343 | Values: 1407.0 1551.1 1586.6 1807.7 1953.0 7981.4 8633.0 8798.8 8815.4 8826.4 8500.4 8731.8 8752.8 8758.9 8764.9 2461.4 2487.4 2488.3 2489.4 2494.8 2425.7 2474.3 2479.1 2481.5 2487.7 2401.5 2416.8 2418.8 2420.8 2425.3 2411.2 2447.5 2448.4 2452.8 2458.7 2409.9 2440.9 2456.1 2460.3 2460.4 8553.1 8815.0 8845.5 8848.0 8848.3 5388.8 5504.5 5544.6 5548.4 5556.7 2977.4 3220.4 3283.7 3320.3 3555.4 1072.0 1623.3 1654.5 1670.6 1706.2 11446.7 11666.4 11674.4 11686.6 11709.8 4537.8 4544.4 4630.9 4646.9 4669.5 9314.7 9322.5 9330.8 9341.1 9344.6 5345.9 5350.4 5359.7 5360.2 5376.5 4171.1 4316.3 4398.9 4886.5 4965.1 3825.9 3841.5 3886.2 3912.6 3932.8 1050.4 1190.9 1208.4 1220.0 1220.4 648.7 832.8 903.0 945.8 952.3 5738.2 5749.8 5798.7 5944.0 6031.7 800.6 959.5 994.4 995.5 1015.5 3805.1 3913.1 3959.9 3963.7 4042.1 10377.3 10798.1 10872.3 10875.6 10891.5 10627.9 10734.6 10743.9 10744.2 10841.3 8364.3 8413.7 8428.3 8433.2 8476.6 5398.6 5766.0 5814.4 5896.2 5944.5 9510.4 9535.2 9547.1 9697.1 9720.3 9473.4 9847.3 9859.4 9865.6 10165.1 6992.5 7007.3 7082.5 7084.6 7085.2 7846.6 8106.5 8162.6 8345.5 8448.7 7357.9 7410.7 7460.4 7485.3 7599.9 7352.5 7378.4 7409.6 7485.3 7551.5 6722.9 6968.2 6972.8 7039.2 7042.4 4860.2 4958.0 5006.8 5069.7 5117.1 5004.8 5069.5 5072.2 5105.3 5274.1 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | [#4] prototype.html Cycles:180 Average:5793.52 Median:6638.87 stddev:1883.73 (28.4%) stddev-sans-first:1843.42 11:55:08 INFO - PROCESS | 7343 | Values: 305.1 319.2 326.7 329.1 333.4 6568.8 6688.3 6709.7 6719.4 6724.6 5276.5 6619.9 6620.6 6625.0 6652.8 3958.0 4683.6 4766.0 4787.0 4787.4 4780.6 4846.8 4860.6 4893.5 4954.5 4430.8 4572.3 4766.7 4835.3 4857.8 3909.7 4932.2 4935.2 4936.3 4938.4 3280.3 4009.2 4153.1 4163.7 4222.0 6356.5 6384.1 6396.6 6403.8 6429.3 5825.9 5830.0 5830.9 5831.2 5834.9 6998.4 7035.9 7037.6 7045.2 7052.7 4690.6 4700.2 4701.6 4711.1 4712.5 7217.7 7527.2 7556.2 7562.5 7567.5 3884.4 4531.3 4638.7 4644.1 4647.3 4720.0 4726.2 4728.0 4731.0 4741.6 5334.3 5626.8 5654.3 5663.5 5673.4 6267.7 6867.6 7139.0 7148.2 7182.4 1374.4 1615.9 1623.1 1637.9 1651.8 882.4 1025.5 1029.0 1048.1 1048.9 6438.4 6704.8 6705.8 6706.5 6717.8 5918.3 7217.8 7318.7 7325.6 7336.2 6017.2 6589.9 6716.8 6752.2 6772.3 3884.5 3931.2 3944.3 3945.9 3955.3 7513.1 7818.5 7824.9 7828.8 7838.3 6915.7 7598.2 7610.4 7625.5 7633.7 6274.9 6917.2 7836.9 7841.9 7844.2 7452.5 7453.2 7482.4 7484.8 7489.7 6978.8 7223.5 7334.2 7335.0 7354.0 7728.4 7759.1 7798.6 7832.6 7845.9 4819.5 4895.2 4896.5 4901.1 4902.5 6215.5 7015.2 7321.6 7328.2 7330.7 6872.7 7141.1 7143.5 7145.7 7203.3 7115.5 7161.4 7182.6 7184.3 7185.1 6983.0 6997.5 6999.5 7010.8 7024.4 7162.4 7165.1 7177.9 7187.4 7224.8 6712.2 7003.2 7005.7 7013.5 7023.5 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - PROCESS | 7343 | [#5] yui.html Cycles:180 Average:17389.20 Median:32275.27 stddev:24748.69 (76.7%) stddev-sans-first:24786.40 11:55:08 INFO - PROCESS | 7343 | Values: 701.8 751.1 756.5 762.4 813.2 14747.9 14749.0 15105.7 15115.4 15126.1 13619.3 13690.8 13693.0 13710.8 13724.0 731.3 738.1 871.5 889.9 904.1 889.5 898.7 900.8 902.3 902.8 938.7 951.9 952.8 953.6 954.2 745.1 934.9 941.4 959.3 963.0 704.8 709.8 713.3 713.4 713.4 14649.4 14749.0 14759.0 14770.7 14775.0 12431.1 12574.8 12741.4 12862.5 12963.5 10002.1 10031.8 10078.2 10161.2 10257.0 3499.7 3567.9 3569.6 3648.2 3658.0 82787.6 82898.6 83040.0 83235.0 83508.6 9347.2 9366.7 9419.6 9485.8 9632.7 17182.0 21187.7 21906.1 22072.9 22095.9 7508.9 7549.3 7802.9 7908.2 7994.7 4088.2 4120.0 4158.9 4185.4 4332.1 3574.8 3633.8 3641.2 3650.8 3669.4 554.6 643.1 660.3 670.1 682.8 535.4 541.9 572.5 579.5 625.2 7665.3 7989.0 8138.7 8159.1 8176.3 559.8 565.5 568.4 599.9 616.9 3749.6 3855.5 3904.5 3959.5 4050.6 88472.0 90642.2 92234.0 92531.5 92768.0 73551.0 73809.6 76692.0 77041.0 77796.8 27902.7 50750.0 68115.0 68423.6 68907.0 7669.0 7826.2 7964.7 7969.0 8097.7 31987.0 40425.6 41818.5 47364.5 47469.8 63875.7 63907.4 64787.4 65056.7 65276.0 9819.4 9916.3 9970.3 10138.0 10679.4 8692.7 11050.9 11186.7 11206.0 12108.7 10236.7 10433.3 10448.2 10543.6 10751.3 10448.7 10515.3 10525.5 10676.9 10687.4 10216.7 10395.8 10459.1 10524.2 10611.3 7362.5 8903.8 8928.0 9180.9 9399.2 8919.5 8959.4 8969.9 9008.6 9145.6 11:55:08 INFO - PROCESS | 7343 | -------- Summary: end -------- 11:55:08 INFO - PROCESS | 7343 | 11:55:08 INFO - TEST-INFO | 7343: exit 0 11:55:08 INFO - TEST-OK | dromaeo_css | took 1115251ms 11:55:08 INFO - TEST-START | kraken 11:55:08 INFO - operating with platform_type : mac_ 11:55:08 INFO - Initialising browser for kraken test... 11:55:08 INFO - TEST-INFO | started process 7345 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpJGjxGG/profile http://localhost:57913/getInfo.html) 11:55:11 INFO - PROCESS | 7345 | __metrics Screen width/height:1600/1200 11:55:11 INFO - PROCESS | 7345 | colorDepth:24 11:55:11 INFO - PROCESS | 7345 | Browser inner width/height: 1024/689 11:55:11 INFO - PROCESS | 7345 | __metrics 11:55:11 INFO - PROCESS | 7345 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:55:11 ERROR - PROCESS | 7345 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:55:11 INFO - TEST-INFO | 7345: exit 0 11:55:11 INFO - Browser initialized. 11:55:11 INFO - Running cycle 1/1 for kraken test... 11:55:11 INFO - TEST-INFO | started process 7347 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpJGjxGG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:55:23 INFO - PROCESS | 7347 | RSS: Main: 211087360 11:55:23 INFO - PROCESS | 7347 | 11:55:26 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57913/tests/kraken/driver.html?testName=audio-beat-detection) 11:55:27 INFO - PROCESS | 7347 | RSS: Main: 272961536 11:55:27 INFO - PROCESS | 7347 | 11:55:30 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57913/tests/kraken/driver.html?testName=audio-dft) 11:55:30 INFO - PROCESS | 7347 | RSS: Main: 286515200 11:55:30 INFO - PROCESS | 7347 | 11:55:33 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57913/tests/kraken/driver.html?testName=audio-fft) 11:55:34 INFO - PROCESS | 7347 | RSS: Main: 373047296 11:55:34 INFO - PROCESS | 7347 | 11:55:36 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57913/tests/kraken/driver.html?testName=audio-oscillator) 11:55:37 INFO - PROCESS | 7347 | RSS: Main: 279040000 11:55:37 INFO - PROCESS | 7347 | 11:55:39 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57913/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:55:39 INFO - PROCESS | 7347 | RSS: Main: 279568384 11:55:39 INFO - PROCESS | 7347 | 11:55:42 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57913/tests/kraken/driver.html?testName=imaging-darkroom) 11:55:43 INFO - PROCESS | 7347 | RSS: Main: 319676416 11:55:43 INFO - PROCESS | 7347 | 11:55:46 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57913/tests/kraken/driver.html?testName=imaging-desaturate) 11:55:47 INFO - PROCESS | 7347 | RSS: Main: 318423040 11:55:47 INFO - PROCESS | 7347 | 11:55:50 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57913/tests/kraken/driver.html?testName=json-parse-financial) 11:55:51 INFO - PROCESS | 7347 | RSS: Main: 317894656 11:55:51 INFO - PROCESS | 7347 | 11:55:52 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57913/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:55:53 INFO - PROCESS | 7347 | RSS: Main: 309739520 11:55:53 INFO - PROCESS | 7347 | 11:55:55 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:55:56 INFO - PROCESS | 7347 | RSS: Main: 317128704 11:55:56 INFO - PROCESS | 7347 | 11:55:58 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:55:58 INFO - PROCESS | 7347 | RSS: Main: 319442944 11:55:58 INFO - PROCESS | 7347 | 11:56:01 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:56:02 INFO - PROCESS | 7347 | RSS: Main: 321331200 11:56:02 INFO - PROCESS | 7347 | 11:56:05 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:56:05 INFO - PROCESS | 7347 | RSS: Main: 317980672 11:56:05 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | Cycle 1(1): loaded http://localhost:57913/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57913/tests/kraken/driver.html?testName=ai-astar) 11:56:07 INFO - PROCESS | 7347 | RSS: Main: 383647744 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | __start_tp_report 11:56:07 INFO - PROCESS | 7347 | _x_x_mozilla_page_load 11:56:07 INFO - PROCESS | 7347 | _x_x_mozilla_page_load_details 11:56:07 INFO - PROCESS | 7347 | |i|pagename|runs| 11:56:07 INFO - PROCESS | 7347 | |0;ai-astar;114;115;122;113;113;112;116;113;114;133 11:56:07 INFO - PROCESS | 7347 | |1;audio-beat-detection;164;104;176;203;154;145;122;155;158;119 11:56:07 INFO - PROCESS | 7347 | |2;audio-dft;186;185;186;184;184;179;183;185;185;185 11:56:07 INFO - PROCESS | 7347 | |3;audio-fft;74;76;75;158;59;75;87;96;148;56 11:56:07 INFO - PROCESS | 7347 | |4;audio-oscillator;94;96;142;77;79;82;125;76;77;125 11:56:07 INFO - PROCESS | 7347 | |5;imaging-gaussian-blur;91;88;89;131;128;87;91;89;90;132 11:56:07 INFO - PROCESS | 7347 | |6;imaging-darkroom;102;151;87;147;146;96;104;152;152;96 11:56:07 INFO - PROCESS | 7347 | |7;imaging-desaturate;84;84;84;77;125;141;117;85;108;143 11:56:07 INFO - PROCESS | 7347 | |8;json-parse-financial;46;52;85;100;98;87;64;81;60;56 11:56:07 INFO - PROCESS | 7347 | |9;json-stringify-tinderbox;65;66;69;73;81;76;71;69;80;68 11:56:07 INFO - PROCESS | 7347 | |10;stanford-crypto-aes;81;82;81;82;81;80;81;80;80;99 11:56:07 INFO - PROCESS | 7347 | |11;stanford-crypto-ccm;129;126;128;127;126;130;127;139;160;149 11:56:07 INFO - PROCESS | 7347 | |12;stanford-crypto-pbkdf2;166;166;165;167;164;165;168;166;167;217 11:56:07 INFO - PROCESS | 7347 | |13;stanford-crypto-sha256-iterative;68;69;68;68;69;73;95;98;75;66 11:56:07 INFO - PROCESS | 7347 | __end_tp_report 11:56:07 INFO - PROCESS | 7347 | __start_cc_report 11:56:07 INFO - PROCESS | 7347 | _x_x_mozilla_cycle_collect,2014 11:56:07 INFO - PROCESS | 7347 | __end_cc_report 11:56:07 INFO - PROCESS | 7347 | __startTimestamp1464720967734__endTimestamp 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | ------- Summary: start ------- 11:56:07 INFO - PROCESS | 7347 | Number of tests: 14 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#0] ai-astar Cycles:10 Average:116.50 Median:114.50 stddev:6.45 (5.6%) stddev-sans-first:6.78 11:56:07 INFO - PROCESS | 7347 | Values: 114.0 115.0 122.0 113.0 113.0 112.0 116.0 113.0 114.0 133.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#1] audio-beat-detection Cycles:10 Average:150.00 Median:156.50 stddev:29.20 (18.7%) stddev-sans-first:30.53 11:56:07 INFO - PROCESS | 7347 | Values: 164.0 104.0 176.0 203.0 154.0 145.0 122.0 155.0 158.0 119.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:2.04 (1.1%) stddev-sans-first:2.06 11:56:07 INFO - PROCESS | 7347 | Values: 186.0 185.0 186.0 184.0 184.0 179.0 183.0 185.0 185.0 185.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#3] audio-fft Cycles:10 Average:90.40 Median:75.00 stddev:35.04 (46.7%) stddev-sans-first:36.66 11:56:07 INFO - PROCESS | 7347 | Values: 74.0 76.0 75.0 158.0 59.0 75.0 87.0 96.0 148.0 56.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:78.00 stddev:24.48 (31.4%) stddev-sans-first:25.93 11:56:07 INFO - PROCESS | 7347 | Values: 94.0 96.0 142.0 77.0 79.0 82.0 125.0 76.0 77.0 125.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#5] imaging-gaussian-blur Cycles:10 Average:101.60 Median:89.00 stddev:19.89 (22.3%) stddev-sans-first:20.72 11:56:07 INFO - PROCESS | 7347 | Values: 91.0 88.0 89.0 131.0 128.0 87.0 91.0 89.0 90.0 132.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#6] imaging-darkroom Cycles:10 Average:123.30 Median:152.00 stddev:28.14 (18.5%) stddev-sans-first:28.77 11:56:07 INFO - PROCESS | 7347 | Values: 102.0 151.0 87.0 147.0 146.0 96.0 104.0 152.0 152.0 96.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#7] imaging-desaturate Cycles:10 Average:104.80 Median:80.50 stddev:25.39 (31.5%) stddev-sans-first:25.78 11:56:07 INFO - PROCESS | 7347 | Values: 84.0 84.0 84.0 77.0 125.0 141.0 117.0 85.0 108.0 143.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#8] json-parse-financial Cycles:10 Average:72.90 Median:72.50 stddev:19.63 (27.1%) stddev-sans-first:18.24 11:56:07 INFO - PROCESS | 7347 | Values: 46.0 52.0 85.0 100.0 98.0 87.0 64.0 81.0 60.0 56.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:5.59 (7.8%) stddev-sans-first:5.36 11:56:07 INFO - PROCESS | 7347 | Values: 65.0 66.0 69.0 73.0 81.0 76.0 71.0 69.0 80.0 68.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:81.00 stddev:5.77 (7.1%) stddev-sans-first:6.09 11:56:07 INFO - PROCESS | 7347 | Values: 81.0 82.0 81.0 82.0 81.0 80.0 81.0 80.0 80.0 99.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#11] stanford-crypto-ccm Cycles:10 Average:134.10 Median:129.50 stddev:11.69 (9.0%) stddev-sans-first:12.25 11:56:07 INFO - PROCESS | 7347 | Values: 129.0 126.0 128.0 127.0 126.0 130.0 127.0 139.0 160.0 149.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:166.50 stddev:16.17 (9.7%) stddev-sans-first:17.04 11:56:07 INFO - PROCESS | 7347 | Values: 166.0 166.0 165.0 167.0 164.0 165.0 168.0 166.0 167.0 217.0 11:56:07 INFO - PROCESS | 7347 | 11:56:07 INFO - PROCESS | 7347 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:71.00 stddev:11.70 (16.5%) stddev-sans-first:12.14 11:56:07 INFO - PROCESS | 7347 | Values: 68.0 69.0 68.0 68.0 69.0 73.0 95.0 98.0 75.0 66.0 11:56:07 INFO - PROCESS | 7347 | -------- Summary: end -------- 11:56:07 INFO - PROCESS | 7347 | 11:56:08 INFO - TEST-INFO | 7347: exit 0 11:56:08 INFO - TEST-OK | kraken | took 59724ms 11:56:08 INFO - SUITE-END | took 1174s 11:56:08 INFO - Completed test suite (00:19:35) 11:56:08 INFO - javascript benchmark 11:56:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.753943454241, 3365.9999999999986, 3382.492626106083, 3383.678550537698, 3447.3450044491547, 5600.6079574429805, 5611.466910643492, 5627.6060675752715, 5645.209670646108, 5656.632318899268, 5619.775208991636, 5661.065924122515, 5667.319921609403, 5675.971620141904, 5678.744456499458, 4318.330658747899, 5112.590992720573, 5140.845774626767, 5164.173732202839, 5223.555997740196, 5211.86970325743, 5259.13224317989, 5260.92108618371, 5269.262303277545, 5275.340582427197, 5146.819861304855, 5183.0, 5214.921776173361, 5217.791288348461, 5243.030039442696, 5213.1919552469235, 5229.267902493654, 5245.973770131163, 5258.8685282867855, 5288.418273989858, 4251.957480425187, 5065.721385323805, 5113.795448182105, 5137.40919794221, 5190.377154741455, 5492.258545096401, 5542.418046105182, 5543.362513310981, 5572.7770889164385, 5614.9893019256515, 6179.876402471926, 6216.378362163793, 6225.844353891143, 6226.59527130735, 6228.564000519966, 5151.381834179885, 5213.687178769248, 6179.84550386244, 6188.133661287423, 6276.121343011982, 5724.914126288109, 5734.827955161351, 5768.394318596487, 5785.82642520725, 5793.188953546507, 6278.089676996809, 6281.96859015702, 6294.339094395023, 6298.937010629927, 6311.652859092794, 6019.909701354483, 6098.573099882966, 6110.663913484801, 6126.479249263773, 6140.293866205444, 6162.876742465126, 6228.813135605939, 6253.0, 6273.560850740404, 6278.811635650938, 6126.908096378558, 6169.96915015422, 6187.257529096537, 6189.381061893865, 6213.161223234805, 6102.786402475892, 6159.5072394208355, 6206.131141640174, 6219.751209951552, 6233.0338797486465, 4808.869915569839, 4873.756312184377, 4907.877303067452, 4920.261960705922, 4937.901241975141, 4400.361947517592, 4413.691041627056, 4433.7561434121435, 4434.290513517822, 4458.063806600585, 5502.5597952163735, 5613.943860561342, 5688.857778555486, 5714.142878568165, 5723.0, 6156.815295541141, 6235.563510554309, 6269.059641053787, 6273.52948528853, 6283.8114856554375, 4863.562279394871, 5669.6314739541795, 5676.8864622707315, 5695.943040569624, 5700.287464066992, 5275.63070585063, 5366.8658283542445, 5413.539849112791, 5419.187121931663, 5419.187121931663, 6249.375062493714, 6270.717817698214, 6315.873682526416, 6323.747050118128, 6337.112804207507, 6230.626162430088, 6260.280067792262, 6280.623162610258, 6284.4972402206795, 6292.1505596743855, 6202.751889924533, 6328.588641738273, 6338.746450141943, 6343.175387199634, 6359.618422894641, 6072.544559158169, 6109.2974307955155, 6118.13190915367, 6123.831729140362, 6126.487138464006, 6127.8615298716295, 6254.655993920378, 6254.8123556293385, 6255.280642726145, 6264.185655864709, 5914.0, 6052.576319657582, 6140.692965351804, 6145.324014358506, 6157.168782214344, 5859.326177489644, 5990.071538911476, 6017.578769486094, 6148.95467770489, 6163.599366041193, 4834.6374021947795, 5948.970255148696, 5961.612495187799, 5973.731182096729, 5998.610090344114, 5891.764329426775, 5953.672548009901, 6055.939440605537, 6091.933911565547, 6102.481289090477, 5790.1893734877995, 5847.619904706181, 5897.5576831736935, 5912.172295878494, 5913.201717768046], "value": 5646.882156244669, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.93266942628014, 492.77086154937945, 500.65204682745497, 509.9923501147483, 521.9138842091055, 38645.61354386455, 39615.60384396159, 39976.99999999996, 40050.0, 40091.0, 43859.0, 44083.55916440843, 44236.0, 44326.77836610821, 44419.0, 8583.82832343356, 8699.739007829776, 9595.08846659568, 9820.214382849355, 9839.311248212627, 8656.567171641424, 11454.885451145507, 11689.941550292237, 11731.120165987542, 11733.002694770954, 11652.533898644044, 11724.531018759239, 11732.589359372423, 11759.294442333445, 11761.823572646417, 8618.870716939187, 11460.828087578693, 11699.23954942926, 11738.765224695544, 11819.586314478953, 11464.426778661034, 11597.594084207096, 11605.955464008195, 11610.825837612443, 11615.245009074382, 33037.50443743346, 41704.1659166818, 41766.58233417657, 41840.790796045825, 41863.58136418627, 3219.3078488125166, 3293.571835661349, 3378.2230087079865, 3383.374075795961, 3565.893023209308, 28872.42255154885, 29184.41631167365, 29238.0, 29337.41325173506, 30072.398552029055, 2237.8097861681777, 2685.0333879803256, 2689.8789554470095, 2756.724327567247, 2758.600002999574, 478902.421951559, 481256.0, 484181.0, 484462.0, 484830.57584712206, 62575.37424625696, 63619.0, 63631.68184159049, 63688.68155659192, 63796.36203637997, 69517.0, 71161.0, 71231.0, 71288.2871171292, 71313.0, 17388.130593470276, 17462.388816391365, 17574.209160587805, 17596.648067038586, 17599.73600395995, 12558.874411255953, 12769.233845969271, 13331.466741330432, 13446.126001809851, 13519.0, 19541.51412068687, 20288.492787680425, 20339.08474118668, 20402.48993775138, 20405.387838364575, 7673.386129109658, 7728.149903510609, 7741.225877412214, 7770.572618506037, 7777.92222077772, 922.2115091596722, 936.9484678342757, 975.0639386189338, 1066.7119877633152, 1095.1239008792934, 30199.396012079636, 30406.08781736507, 30742.846285768428, 30762.0, 30856.53715194274, 1076.8438576406534, 1089.6814854026559, 1137.2323681514945, 1241.4103300931986, 1274.5411651805343, 15416.922915385352, 15508.0, 15779.526614201592, 15808.227732046147, 15859.682806343808, 598084.0, 602936.0, 602988.0, 603695.0, 605463.0, 59228.0, 59764.0, 60646.393536064075, 60695.08957365643, 60779.39220607738, 1361.360160724458, 1387.500499820064, 1411.56947131126, 1417.5959851442497, 1511.894167408271, 23223.76776232216, 29329.85335073311, 31520.0, 31539.684603154594, 31553.368932621677, 52795.736021319644, 53190.73404632952, 53320.46679533155, 53325.73337133289, 53355.466445336606, 372173.1391343026, 380511.0, 381788.0, 383325.0, 384061.0, 24006.87996560006, 24436.14473493419, 26890.59664105079, 27264.59103113415, 27653.0, 42480.17678408958, 42530.14939701251, 42973.570264296955, 43557.3466397998, 43829.780851095544, 34665.306693866485, 34933.0, 36148.0, 36151.63848361483, 36722.4491632631, 35358.823205883804, 35551.82224088863, 35660.46509302311, 36427.0, 36448.63551364558, 36565.0, 37252.0, 37534.6246537542, 37622.8118859404, 37850.0537486565, 31586.368272633958, 33002.66997329996, 33174.17064573405, 33196.668033320326, 33234.501482477295, 24284.51430971406, 24755.62866556967, 24842.875785620956, 25221.74778252194, 25265.0], "value": 20105.845798211834, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.29631292720654, 395.1957765946299, 396.5162501747868, 399.60638770810766, 404.4883222723254, 19269.614607707837, 20461.283855065074, 21265.14939402424, 21363.145474181016, 21384.679229811565, 21151.788482115175, 21166.153353865866, 21477.463063423424, 21912.45218869525, 22566.661500077473, 5770.422957704217, 5897.351291357947, 6013.819585412444, 6116.113163591277, 6117.082437634368, 5961.195238642771, 5978.192943952576, 6020.45815876571, 6024.186734790791, 6034.547408944325, 4945.950540494603, 5941.7029148542615, 5975.850603734898, 5981.431763982415, 5994.8801023979495, 5854.473097421232, 5863.120531920204, 5910.586258961875, 5911.793087241969, 5918.2898052234, 5119.744012799345, 5196.68819870809, 5202.0, 5210.635255532115, 5220.530152286313, 26330.21009369722, 26331.0, 26723.93104275846, 26728.797204125665, 26783.86608066947, 2003.8497112716605, 2769.9307517312027, 2931.4723349797027, 2944.440556294297, 2949.2626843289177, 9888.505574721235, 10014.649487267983, 10033.648822291185, 10066.094051535323, 10249.538770755335, 1693.7036018696679, 1726.2059452651795, 1738.1048759888668, 1765.62921786425, 1789.33794496036, 58835.11747323794, 64518.0, 104656.47671761592, 105506.0, 108292.4585377076, 11747.29516229029, 11778.88221117795, 11805.173637845268, 11812.940935295268, 12056.63830085099, 15174.696506069817, 15189.468368607046, 15191.480851914721, 15219.771703424458, 15230.162341071347, 7927.0, 11184.217104802749, 11248.662540123809, 11641.59254426091, 11647.53409863613, 9408.858867116998, 9557.665481708107, 9564.95217523908, 9727.805443891224, 9782.315237933277, 8273.007239131222, 9615.278854085971, 9879.50602469888, 9977.650782222732, 9982.401055936667, 3433.296174284266, 3479.5998460176897, 3722.4788529605876, 3751.7748935063983, 3900.395438707005, 880.5729221327651, 1022.7801022780138, 1049.3965969567378, 1051.264115119404, 1067.2315932528568, 20411.979401030185, 20889.79110208909, 21129.0, 21156.894215529133, 21312.57374852494, 935.1817159985013, 962.6967505235829, 981.3228872078244, 989.2926557511414, 1006.3609607898891, 10317.884833609092, 11047.392393418439, 11217.887821121847, 11286.943565282121, 11308.378039207922, 585896.0, 593555.0, 608207.0, 611411.0, 615164.0, 59840.40159598435, 69713.0, 111709.44145279384, 113080.0, 113326.0, 857.7341024282492, 1002.7142264454741, 1015.2994433840627, 1015.4567306490992, 1016.3596933931576, 17854.107294634956, 17985.550361241072, 18089.36687215941, 18292.35976740861, 18808.965506896704, 50795.74602126966, 50857.47427577178, 50910.490895090574, 51787.48212517826, 52362.73818630882, 67963.66018169878, 68486.65756671184, 69428.6528567354, 70736.29263707504, 73248.0, 7996.800079998046, 8040.87938680932, 8156.143604921398, 8254.009518857536, 8272.131426200403, 9081.046490118706, 11679.299242045505, 12210.511579536718, 12352.876471235173, 12474.313912734886, 10724.088452481627, 10931.890681093088, 10997.285176463503, 11012.614558490415, 11110.333379997226, 6612.866174605167, 6628.966855165693, 7356.337929586363, 7414.406847452299, 8103.473274237156, 7867.095284042409, 8001.559914204775, 8050.758477245692, 8080.474769139987, 8378.287845532954, 11511.366874821964, 11642.592509262135, 12011.279323240633, 12205.0, 12290.508379664714, 10782.137429005817, 11246.437678116225, 11307.547698091985, 11472.0, 11525.193236473702], "value": 9827.702907417604, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.0, 1551.123615157436, 1586.595418168367, 1807.7198034304688, 1952.959157680225, 7981.441299109065, 8633.0, 8798.780030499242, 8815.426997245182, 8826.426282291646, 8500.447470914387, 8731.825363492726, 8752.824943501126, 8758.91241087589, 8764.91235087649, 2461.4338702098485, 2487.4278915849277, 2488.3032750829707, 2489.390099425634, 2494.762997515233, 2425.6967879015124, 2474.2948259745976, 2479.0579579759665, 2481.5161043596504, 2487.676602041732, 2401.543706695731, 2416.8066554675584, 2418.7823095921362, 2420.782129608335, 2425.2966639674473, 2411.240459255329, 2447.49826285611, 2448.4001419652122, 2452.8037756979397, 2458.729539750626, 2409.891554880017, 2440.865752383618, 2456.0666946560373, 2460.274219105368, 2460.4341001569737, 8553.101924297984, 8815.0, 8845.513496757676, 8848.026717061119, 8848.336374771854, 5388.811391601275, 5504.532114770249, 5544.556435485151, 5548.4174161712845, 5556.749946252428, 2977.3598676284487, 3220.4364236258793, 3283.655216202312, 3320.3193345364143, 3555.3600351936643, 1072.0458791675414, 1623.3344328825242, 1654.5119189839027, 1670.6157583755682, 1706.2066139245187, 11446.656600301922, 11666.416679166092, 11674.41627918609, 11686.590969316117, 11709.765804683859, 4537.795799189044, 4544.409226800561, 4630.930536041962, 4646.930296045562, 4669.486356500782, 9314.673986410444, 9322.487263200454, 9330.766730831783, 9341.11259430353, 9344.579493922789, 5345.893082138336, 5350.357957045102, 5359.732013399314, 5360.249565060894, 5376.462353764592, 4171.10321280926, 4316.309390497506, 4398.856297362709, 4886.486918873538, 4965.0566392385335, 3825.885223443301, 3841.5390153181797, 3886.2227554489214, 3912.6478616924614, 3932.803359831997, 1050.3540322701483, 1190.9166358354832, 1208.3897631695938, 1219.9817002744967, 1220.4263995921901, 648.6918713610997, 832.7918020494876, 902.963881444748, 945.7871978804851, 952.3143336797494, 5738.196652468658, 5749.7700091996685, 5798.710064496759, 5944.0, 6031.698415079316, 800.6109400190664, 959.4579062829479, 994.3586386780486, 995.5370752600076, 1015.4719545836124, 3805.1438426354403, 3913.099987002948, 3959.920801583952, 3963.742356746802, 4042.130941847517, 10377.27359084872, 10798.082163016075, 10872.347659140476, 10875.564977400974, 10891.455427228608, 10627.937206279465, 10734.57061717538, 10743.892561074446, 10744.247902646895, 10841.349519028883, 8364.330853531703, 8413.70552030676, 8428.283595894418, 8433.241008309158, 8476.618552165168, 5398.64908780928, 5765.97117014429, 5814.418558144152, 5896.174535564938, 5944.456053414523, 9510.38182518134, 9535.189508891823, 9547.140757331874, 9697.078777516124, 9720.319577629498, 9473.4315941041, 9847.261455390731, 9859.408435493893, 9865.605375784888, 10165.13596344319, 6992.510524263253, 7007.264237255014, 7082.4688148390105, 7084.64576771149, 7085.220625731063, 7846.646900889359, 8106.47307924983, 8162.632681529227, 8345.499270043816, 8448.746537603882, 7357.926420735724, 7410.703571857065, 7460.365868900987, 7485.288897554724, 7599.886001709868, 7352.48532602734, 7378.372838308804, 7409.555426674417, 7485.326320631169, 7551.509151905108, 6722.865542689021, 6968.198657154289, 6972.7908162753165, 7039.190493093155, 7042.366187043191, 4860.17377045896, 4958.008398320422, 5006.774695138654, 5069.670471419346, 5117.1300878851425, 5004.799808007784, 5069.518395752398, 5072.188449848154, 5105.33630628016, 5274.12976858814], "value": 4621.2054878421895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.12581454133897, 319.23861590107595, 326.7337120247, 329.14586647649344, 333.386568713567, 6568.802935911925, 6688.264290927994, 6709.66451677416, 6719.361660642237, 6724.630145342005, 5276.498732620405, 6619.900701489481, 6620.569662971916, 6624.966875165629, 6652.767153149641, 3957.990712368342, 4683.648726345529, 4765.999140180566, 4787.042591481701, 4787.353707249529, 4780.617550595944, 4846.830360937368, 4860.562549370574, 4893.4617192108835, 4954.504549545053, 4430.803683005586, 4572.268437050073, 4766.73782941938, 4835.323054772335, 4857.781399837016, 3909.7263191576603, 4932.210846264598, 4935.210366341368, 4936.308916751622, 4938.38270216223, 3280.327532855759, 4009.1781184857327, 4153.1486045360625, 4163.708540402173, 4221.957780422187, 6356.459700925427, 6384.138141350904, 6396.552241343108, 6403.807885763435, 6429.324920883333, 5825.88348233033, 5830.0380437227805, 5830.854228644275, 5831.154482600042, 5834.854128646775, 6998.405135563483, 7035.929640703579, 7037.648117594151, 7045.154581450259, 7052.682629281643, 4690.577847993697, 4700.2009658358165, 4701.623870090419, 4711.128441238382, 4712.528747125294, 7217.7473788417155, 7527.209642987462, 7556.2443755624545, 7562.546247225184, 7567.508111972759, 3884.378499440076, 4531.342955271467, 4638.744869032235, 4644.140833945663, 4647.34937108805, 4720.0087981523575, 4726.172919739032, 4728.0, 4731.030138821533, 4741.596964258008, 5334.306540149756, 5626.818368142736, 5654.293213348332, 5663.518600918886, 5673.375928647845, 6267.655278959701, 6867.587944723333, 7139.0, 7148.213696493382, 7182.3535881770895, 1374.3609221711952, 1615.935362585507, 1623.091069001363, 1637.9426720064741, 1651.8100418452032, 882.3911501064246, 1025.4821315235906, 1028.984565231522, 1048.0567489259574, 1048.8793788714243, 6438.420542151229, 6704.7988560343265, 6705.832354191184, 6706.524564595781, 6717.764878229238, 5918.289805223315, 7217.783466496014, 7318.707251709979, 7325.560466372035, 7336.193018767931, 6017.157597936292, 6589.86820263592, 6716.764913228111, 6752.155980502437, 6772.288909664513, 3884.514435695538, 3931.233409485123, 3944.3097457944746, 3945.940810887839, 3955.3275943089743, 7513.1359893612935, 7818.452708310478, 7824.92175078242, 7828.765137045898, 7838.294553490213, 6915.688794004182, 7598.164201937782, 7610.391168706601, 7625.504342217738, 7633.694652213961, 6274.874502509924, 6917.169939607279, 7836.882446763303, 7841.882371764542, 7844.17636148219, 7452.478326517092, 7453.217412171643, 7482.401407887465, 7484.775456736306, 7489.662965166472, 6978.755743548952, 7223.494355395072, 7334.193238743627, 7334.96332518334, 7354.043974283309, 7728.420368472274, 7759.068911730403, 7798.610069496616, 7832.608369581612, 7845.882311765214, 4819.49395313487, 4895.216765317532, 4896.510348965075, 4901.14230009754, 4902.509749025069, 6215.508278013154, 7015.193252775996, 7321.63391830417, 7328.2305357938785, 7330.743423980135, 6872.725090996304, 7141.143062832286, 7143.464240181903, 7145.714171433085, 7203.315685010125, 7115.53749006313, 7161.39128174111, 7182.640867956686, 7184.317489838457, 7185.065941427581, 6983.0, 6997.510174287751, 6999.545029573062, 7010.824729381602, 7024.367806897404, 7162.355388015104, 7165.068541089625, 7177.892331614926, 7187.353138217585, 7224.819379515344, 6712.241144958839, 7003.194632617314, 7005.719771209298, 7013.544119632207, 7023.543469674455], "value": 5153.637400816629, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.7543859649122, 751.1174370115114, 756.4666909828571, 762.4319881688143, 813.1583810755869, 14747.926260368697, 14749.0, 15105.697886042273, 15115.39538418462, 15126.092434453927, 13619.25094119823, 13690.794638080437, 13693.0, 13710.794338084937, 13723.97070219735, 731.2869951797009, 738.1474397071364, 871.4509858788986, 889.8576227803569, 904.055262250946, 889.5196593839319, 898.7258886039755, 900.8243392538458, 902.3277658144691, 902.7923577577161, 938.6667732954805, 951.9286053545977, 952.8284908716428, 953.6423841059602, 954.2080073538981, 745.124478737484, 934.9298802589833, 941.4351389166513, 959.3045042344314, 963.0129117654391, 704.7815177295055, 709.765777293492, 713.3437237741291, 713.425692317684, 713.4399496395305, 14649.414023439049, 14749.0, 14759.040662356912, 14770.704585908328, 14775.0, 12431.12982091254, 12574.81137782934, 12741.36293185337, 12862.549810756578, 12963.48146074156, 10002.149817265541, 10031.849522257173, 10078.244131690079, 10161.18710503158, 10257.0, 3499.65003499648, 3567.8572857085687, 3569.607343192247, 3648.1791596890753, 3657.963420365789, 82787.5860620693, 82898.58550707207, 83040.0, 83235.0, 83508.58245708793, 9347.158755712018, 9366.718998430058, 9419.576119074658, 9485.810283794286, 9632.662856799978, 17182.0, 21187.68218476724, 21906.12375504962, 22072.88963555204, 22095.889520552617, 7508.924910750822, 7549.320561149523, 7802.921970780219, 7908.209179082046, 7994.68021279154, 4088.1619268049976, 4119.958800411958, 4158.9376159357635, 4185.351270553069, 4332.090261045153, 3574.803385813805, 3633.781973081571, 3641.198936234024, 3650.762700424464, 3669.4495825625836, 554.5508138407822, 643.1446176585131, 660.2704012066597, 670.1321788284183, 682.8224661587924, 535.3736128729396, 541.9105847535192, 572.5391060196582, 579.4524174654995, 625.2028663454041, 7665.348445382094, 7988.960055199686, 8138.674453021813, 8159.143289954469, 8176.3458923286, 559.815260963881, 565.5277843001057, 568.4372471253429, 599.9010163323003, 616.8735409241095, 3749.5875453700073, 3855.5373355197557, 3904.5314562252142, 3959.544652364957, 4050.5544390117066, 88472.0, 90642.1871562565, 92234.0, 92531.53734231286, 92768.0, 73551.0, 73809.63095184597, 76692.0, 77041.0, 77796.83304750433, 27902.72097279001, 50750.0, 68115.0, 68423.63152736917, 68906.9663955031, 7668.961655191688, 7826.178251283533, 7964.721234756756, 7968.960155199187, 8097.71657991991, 31987.0, 40425.59574404336, 41818.53635122878, 47364.52635473601, 47469.762651186524, 63875.68062159659, 63907.4023149425, 64787.35212647813, 65056.67471662611, 65276.0, 9819.410835349901, 9916.305858590118, 9970.30207885441, 10138.03688649577, 10679.412632305297, 8692.652293908173, 11050.944745276543, 11186.720331991766, 11206.0, 12108.697282568006, 10236.692899213036, 10433.32183408076, 10448.216383771096, 10543.57825686964, 10751.301165424222, 10448.68653940383, 10515.316504427188, 10525.473726313807, 10676.93230676926, 10687.358758474516, 10216.693499195037, 10395.844062339222, 10459.058684718411, 10524.158067354747, 10611.257211995086, 7362.484626076123, 8903.777405564913, 8928.0, 9180.908190918006, 9399.154076133182, 8919.50253928901, 8959.372843900865, 8969.865452018355, 9008.594613242289, 9145.5884485197], "value": 6692.422500675292, "unit": "score"}], "name": "dromaeo_css", "value": 7499.154879289864, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 122.0, 113.0, 113.0, 112.0, 116.0, 113.0, 114.0, 133.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 104.0, 176.0, 203.0, 154.0, 145.0, 122.0, 155.0, 158.0, 119.0], "value": 150.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 186.0, 184.0, 184.0, 179.0, 183.0, 185.0, 185.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 158.0, 59.0, 75.0, 87.0, 96.0, 148.0, 56.0], "value": 90.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 142.0, 77.0, 79.0, 82.0, 125.0, 76.0, 77.0, 125.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 89.0, 131.0, 128.0, 87.0, 91.0, 89.0, 90.0, 132.0], "value": 101.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 151.0, 87.0, 147.0, 146.0, 96.0, 104.0, 152.0, 152.0, 96.0], "value": 123.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 77.0, 125.0, 141.0, 117.0, 85.0, 108.0, 143.0], "value": 104.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 52.0, 85.0, 100.0, 98.0, 87.0, 64.0, 81.0, 60.0, 56.0], "value": 72.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 69.0, 73.0, 81.0, 76.0, 71.0, 69.0, 80.0, 68.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 82.0, 81.0, 80.0, 81.0, 80.0, 80.0, 99.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 126.0, 128.0, 127.0, 126.0, 130.0, 127.0, 139.0, 160.0, 149.0], "value": 134.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 165.0, 167.0, 164.0, 165.0, 168.0, 166.0, 167.0, 217.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 69.0, 73.0, 95.0, 98.0, 75.0, 66.0], "value": 74.9, "unit": "score"}], "name": "kraken", "value": 1575.6, "alertThreshold": 2.0}]} 11:56:08 INFO - Return code: 0 11:56:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:56:09 ERROR - # TBPL SUCCESS # 11:56:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:56:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:56:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:56:09 INFO - 2016-05-31 11:56:09.036 screenresolution[7351:128382] starting screenresolution argv=screenresolution get 11:56:09 INFO - 2016-05-31 11:56:09.039 screenresolution[7351:128382] Display 0: 1600x1200x32@60 11:56:09 INFO - 2016-05-31 11:56:09.045 screenresolution[7352:128385] starting screenresolution argv=screenresolution list 11:56:09 INFO - Available Modes on Display 0 11:56:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:56:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:56:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:56:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:56:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:56:09 INFO - Intel Iris: 11:56:09 INFO - Chipset Model: Intel Iris 11:56:09 INFO - Type: GPU 11:56:09 INFO - Bus: Built-In 11:56:09 INFO - VRAM (Dynamic, Max): 1536 MB 11:56:09 INFO - Vendor: Intel (0x8086) 11:56:09 INFO - Device ID: 0x0a2e 11:56:09 INFO - Revision ID: 0x0009 11:56:09 INFO - Displays: 11:56:09 INFO - Display: 11:56:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:56:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:56:09 INFO - Display Serial Number: 1600x1200 60 11:56:09 INFO - Main Display: Yes 11:56:09 INFO - Mirror: Off 11:56:09 INFO - Online: Yes 11:56:09 INFO - Rotation: Supported 11:56:09 INFO - Return code: 0 11:56:09 INFO - Running post-action listener: _resource_record_post_action 11:56:09 INFO - Running post-run listener: _resource_record_post_run 11:56:10 INFO - Total resource usage - Wall time: 1209s; CPU: 52.0%; Read bytes: 929792; Write bytes: 651074560; Read time: 6; Write time: 1808 11:56:10 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346954240; Write bytes: 346404864; Read time: 27372; Write time: 679 11:56:10 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 929792; Write bytes: 294646784; Read time: 6; Write time: 1091 11:56:10 INFO - Running post-run listener: _upload_blobber_files 11:56:10 INFO - Blob upload gear active. 11:56:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:56:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:56:10 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:56:10 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:56:10 INFO - (blobuploader) - INFO - Open directory for files ... 11:56:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:56:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:56:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:11 INFO - (blobuploader) - INFO - Done attempting. 11:56:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:56:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:56:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:12 INFO - (blobuploader) - INFO - Done attempting. 11:56:12 INFO - (blobuploader) - INFO - Iteration through files over. 11:56:12 INFO - Return code: 0 11:56:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:56:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:56:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0990fa51bbc3d475ea00e711395777a4cc2475b2afd4812cfe63971da5a28b7a685be40213925052c52396a056b4cce8723b6c8603630fe53dd71dccd8590a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e0f0f731b692e640c2c6286ffbc871602ccba45ab9829cf69e41ab37b92921dc0a2cfc19150dc2d15606c9cf55d98c032d48f8d5f2fa3d9997cd42f2313e43"} 11:56:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:56:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:56:12 INFO - Contents: 11:56:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0990fa51bbc3d475ea00e711395777a4cc2475b2afd4812cfe63971da5a28b7a685be40213925052c52396a056b4cce8723b6c8603630fe53dd71dccd8590a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e0f0f731b692e640c2c6286ffbc871602ccba45ab9829cf69e41ab37b92921dc0a2cfc19150dc2d15606c9cf55d98c032d48f8d5f2fa3d9997cd42f2313e43"} 11:56:12 INFO - Running post-run listener: copy_logs_to_upload_dir 11:56:12 INFO - Copying logs to upload dir... 11:56:12 INFO - mkdir: /builds/slave/test/build/upload/logs 11:56:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.059005 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-31 11:56:12.860990) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 11:56:12.864908) ========= 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.n2qUY2FDUZ/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.D001pJKNp4/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0990fa51bbc3d475ea00e711395777a4cc2475b2afd4812cfe63971da5a28b7a685be40213925052c52396a056b4cce8723b6c8603630fe53dd71dccd8590a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e0f0f731b692e640c2c6286ffbc871602ccba45ab9829cf69e41ab37b92921dc0a2cfc19150dc2d15606c9cf55d98c032d48f8d5f2fa3d9997cd42f2313e43"} build_url:https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011619 build_url: 'https://queue.taskcluster.net/v1/task/JwJZ5F6IRHGKqn72kTvI9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0990fa51bbc3d475ea00e711395777a4cc2475b2afd4812cfe63971da5a28b7a685be40213925052c52396a056b4cce8723b6c8603630fe53dd71dccd8590a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90e0f0f731b692e640c2c6286ffbc871602ccba45ab9829cf69e41ab37b92921dc0a2cfc19150dc2d15606c9cf55d98c032d48f8d5f2fa3d9997cd42f2313e43"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 11:56:12.899747) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:56:12.900453) ========= 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.n2qUY2FDUZ/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.D001pJKNp4/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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.005516 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:56:12.953889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 11:56:12.954438) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 11:56:12.955074) ========= ========= Total master_lag: 3.96 =========