builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0017 starttime: 1461662913.5 results: success (0) buildid: 20160426013609 builduid: a5e98f8cff294f8f9c4a821f14d5edb9 revision: 99fbe8621fa73724446ea9f828a0d30642faa6f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.500591) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.501061) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.502316) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.528489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.528880) ========= 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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-04-26 02:28:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 64.3M=0s 2016-04-26 02:28:33 (64.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114743 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.663732) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.664054) ========= 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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.027716 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:33.716989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 02:28:33.718365) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 99fbe8621fa73724446ea9f828a0d30642faa6f9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 99fbe8621fa73724446ea9f828a0d30642faa6f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Knjc4ApWB6/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.YEW6AzQDoj/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-04-26 02:28:33,789 truncating revision to first 12 chars 2016-04-26 02:28:33,789 Setting DEBUG logging. 2016-04-26 02:28:33,789 attempt 1/10 2016-04-26 02:28:33,789 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/99fbe8621fa7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 02:28:34,558 unpacking tar archive at: fx-team-99fbe8621fa7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.015647 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 02:28:34.750833) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:34.754207) ========= script_repo_revision: 99fbe8621fa73724446ea9f828a0d30642faa6f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:34.754649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:34.754922) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 02:28:34.771320) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-26 02:28:34.771754) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Knjc4ApWB6/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.YEW6AzQDoj/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:28:34 INFO - MultiFileLogger online at 20160426 02:28:34 in /builds/slave/test 02:28:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:28:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:34 INFO - {'append_to_log': False, 02:28:34 INFO - 'base_work_dir': '/builds/slave/test', 02:28:34 INFO - 'blob_upload_branch': 'Fx-Team', 02:28:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:34 INFO - 'branch': 'Fx-Team', 02:28:34 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:34 INFO - 'config_files': ('talos/mac_config.py',), 02:28:34 INFO - 'default_actions': ('clobber', 02:28:34 INFO - 'read-buildbot-config', 02:28:34 INFO - 'download-and-extract', 02:28:34 INFO - 'populate-webroot', 02:28:34 INFO - 'create-virtualenv', 02:28:34 INFO - 'install', 02:28:34 INFO - 'run-tests'), 02:28:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:34 INFO - 'download_minidump_stackwalk': True, 02:28:34 INFO - 'download_symbols': 'ondemand', 02:28:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:28:34 INFO - 'tooltool.py': '/tools/tooltool.py', 02:28:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:28:34 INFO - '/tools/misc-python/virtualenv.py')}, 02:28:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:34 INFO - 'installer_path': 'installer.exe', 02:28:34 INFO - 'log_level': 'info', 02:28:34 INFO - 'log_name': 'talos', 02:28:34 INFO - 'log_to_console': True, 02:28:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:28:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:28:34 INFO - 'opt_config_files': (), 02:28:34 INFO - 'pip_index': False, 02:28:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:34 INFO - 'cmd': ('bash', 02:28:34 INFO - '-c', 02:28:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:34 INFO - 'enabled': True, 02:28:34 INFO - 'halt_on_failure': False, 02:28:34 INFO - 'name': 'check_screen_resolution'},), 02:28:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:34 INFO - 'cmd': ('bash', 02:28:34 INFO - '-c', 02:28:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:34 INFO - 'enabled': True, 02:28:34 INFO - 'halt_on_failure': False, 02:28:34 INFO - 'name': 'check_screen_resolution'},), 02:28:34 INFO - 'run_cmd_checks_enabled': True, 02:28:34 INFO - 'sps_profile': False, 02:28:34 INFO - 'sps_profile_interval': 0, 02:28:34 INFO - 'suite': 'dromaeojs', 02:28:34 INFO - 'system_bits': '32', 02:28:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:34 INFO - 'title': 't-yosemite-r7-0017', 02:28:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:34 INFO - 'use_talos_json': True, 02:28:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:34 INFO - 'work_dir': 'build'} 02:28:34 INFO - ##### 02:28:34 INFO - ##### Running clobber step. 02:28:34 INFO - ##### 02:28:34 INFO - Running pre-action listener: _resource_record_pre_action 02:28:34 INFO - Running main action method: clobber 02:28:34 INFO - rmtree: /builds/slave/test/build 02:28:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:36 INFO - Running post-action listener: _resource_record_post_action 02:28:36 INFO - ##### 02:28:36 INFO - ##### Running read-buildbot-config step. 02:28:36 INFO - ##### 02:28:36 INFO - Running pre-action listener: _resource_record_pre_action 02:28:36 INFO - Running main action method: read_buildbot_config 02:28:36 INFO - Using buildbot properties: 02:28:36 INFO - { 02:28:36 INFO - "project": "", 02:28:36 INFO - "product": "firefox", 02:28:36 INFO - "script_repo_revision": "production", 02:28:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:28:36 INFO - "repository": "", 02:28:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:28:36 INFO - "buildid": "20160426013609", 02:28:36 INFO - "pgo_build": "False", 02:28:36 INFO - "basedir": "/builds/slave/test", 02:28:36 INFO - "buildnumber": 772, 02:28:36 INFO - "slavename": "t-yosemite-r7-0017", 02:28:36 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:28:36 INFO - "platform": "yosemite_r7", 02:28:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:28:36 INFO - "branch": "fx-team", 02:28:36 INFO - "repo_path": "integration/fx-team", 02:28:36 INFO - "revision": "99fbe8621fa73724446ea9f828a0d30642faa6f9", 02:28:36 INFO - "stage_platform": "macosx64", 02:28:36 INFO - "builduid": "a5e98f8cff294f8f9c4a821f14d5edb9", 02:28:36 INFO - "slavebuilddir": "test" 02:28:36 INFO - } 02:28:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 02:28:36 INFO - Running post-action listener: _resource_record_post_action 02:28:36 INFO - ##### 02:28:36 INFO - ##### Running download-and-extract step. 02:28:36 INFO - ##### 02:28:36 INFO - Running pre-action listener: _resource_record_pre_action 02:28:36 INFO - Running main action method: download_and_extract 02:28:36 INFO - mkdir: /builds/slave/test/build/tests 02:28: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')]} 02:28:36 INFO - https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:28:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:28:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:28:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 02:28:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 02:28:41 INFO - Downloaded 1199 bytes. 02:28:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 02:28:41 INFO - Using the following test package requirements: 02:28:41 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:28:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 02:28:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 02:28:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'jsshell-mac.zip'], 02:28:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 02:28:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:28:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 02:28:41 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 02:28:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 02:28:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:28:41 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 02:28:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:28: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')]} 02:28:41 INFO - https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:28:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:28:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:28:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:28:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:28:43 INFO - Downloaded 18113186 bytes. 02:28:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:28:46 INFO - Return code: 0 02:28:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:28:46 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')]} 02:28:46 INFO - https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:28:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:28:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:28:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:28:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:28:47 INFO - Downloaded 18113186 bytes. 02:28:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:28:51 INFO - Return code: 0 02:28: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')]} 02:28:51 INFO - https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:28:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:28:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:28:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:28:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:28:52 INFO - Downloaded 10840145 bytes. 02:28:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:28:52 INFO - Return code: 0 02:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:52 INFO - https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:28:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:28:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:28:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 02:28:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 02:28:55 INFO - Downloaded 110095187 bytes. 02:28:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:28:55 INFO - mkdir: /builds/slave/test/properties 02:28:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:28:55 INFO - Writing to file /builds/slave/test/properties/build_url 02:28:55 INFO - Contents: 02:28:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:28:57 INFO - Running post-action listener: _resource_record_post_action 02:28:57 INFO - Running post-action listener: set_extra_try_arguments 02:28:57 INFO - ##### 02:28:57 INFO - ##### Running populate-webroot step. 02:28:57 INFO - ##### 02:28:57 INFO - Running pre-action listener: _resource_record_pre_action 02:28:57 INFO - Running main action method: populate_webroot 02:28:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:28:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:28:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:28:57 INFO - '--tppagecycles', 02:28:57 INFO - '7'], 02:28:57 INFO - 'tests': ['tsvgm']}}, 02:28:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:28:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:28:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:28:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:28:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['damp', 'tps']}, 02:28:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'tests': ['damp', 'tps']}, 02:28:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['dromaeo_dom']}, 02:28:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:28:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['a11yr', 02:28:57 INFO - 'ts_paint', 02:28:57 INFO - 'tpaint', 02:28:57 INFO - 'sessionrestore', 02:28:57 INFO - 'sessionrestore_no_auto_restore', 02:28:57 INFO - 'tabpaint']}, 02:28:57 INFO - 'other-e10s': {'tests': ['a11yr', 02:28:57 INFO - 'ts_paint', 02:28:57 INFO - 'tpaint', 02:28:57 INFO - 'sessionrestore', 02:28:57 INFO - 'sessionrestore_no_auto_restore', 02:28:57 INFO - 'tabpaint']}, 02:28:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:28:57 INFO - 'ts_paint', 02:28:57 INFO - 'tpaint', 02:28:57 INFO - 'sessionrestore', 02:28:57 INFO - 'sessionrestore_no_auto_restore', 02:28:57 INFO - 'tabpaint']}, 02:28:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:28:57 INFO - 'ts_paint', 02:28:57 INFO - 'tpaint', 02:28:57 INFO - 'sessionrestore', 02:28:57 INFO - 'sessionrestore_no_auto_restore', 02:28:57 INFO - 'tabpaint']}, 02:28:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['a11yr', 02:28:57 INFO - 'ts_paint', 02:28:57 INFO - 'tpaint', 02:28:57 INFO - 'sessionrestore', 02:28:57 INFO - 'sessionrestore_no_auto_restore', 02:28:57 INFO - 'tabpaint']}, 02:28:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['a11yr', 02:28:57 INFO - 'ts_paint', 02:28:57 INFO - 'tpaint', 02:28:57 INFO - 'sessionrestore', 02:28:57 INFO - 'sessionrestore_no_auto_restore', 02:28:57 INFO - 'tabpaint']}, 02:28:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['tsvgx', 02:28:57 INFO - 'tsvgr_opacity', 02:28:57 INFO - 'tart', 02:28:57 INFO - 'tscrollx', 02:28:57 INFO - 'cart']}, 02:28:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:28:57 INFO - 'tsvgr_opacity', 02:28:57 INFO - 'tart', 02:28:57 INFO - 'tscrollx', 02:28:57 INFO - 'cart']}, 02:28:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'talos_options': ['--disable-e10s'], 02:28:57 INFO - 'tests': ['tp5o']}, 02:28:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'tests': ['tp5o']}, 02:28:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'talos_options': ['--disable-e10s', 02:28:57 INFO - '--xperf_path', 02:28:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:57 INFO - 'tests': ['tp5n']}, 02:28:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:57 INFO - 'talos_options': ['--xperf_path', 02:28:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:57 INFO - 'tests': ['tp5n']}}, 02:28:57 INFO - 'talos.zip': {'path': '', 02:28:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:28:57 INFO - Running post-action listener: _resource_record_post_action 02:28:57 INFO - ##### 02:28:57 INFO - ##### Running create-virtualenv step. 02:28:57 INFO - ##### 02:28:57 INFO - Running pre-action listener: _resource_record_pre_action 02:28:57 INFO - Running main action method: create_virtualenv 02:28:57 INFO - Creating virtualenv /builds/slave/test/build/venv 02:28:57 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 02:28:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:28:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:28:57 INFO - Using real prefix '/tools/python27' 02:28:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:28:58 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:00 INFO - Installing pip.................done. 02:29:00 INFO - Return code: 0 02:29:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:00 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')]} 02:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:00 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')]} 02:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:00 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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:00 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 02:29:00 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 02:29:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:00 INFO - 'HOME': '/Users/cltbld', 02:29:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:00 INFO - 'LOGNAME': 'cltbld', 02:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:00 INFO - 'MOZ_NO_REMOTE': '1', 02:29:00 INFO - 'NO_EM_RESTART': '1', 02:29:00 INFO - 'PAGER': '/bin/cat', 02:29:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:00 INFO - 'PWD': '/builds/slave/test', 02:29:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:00 INFO - 'SHELL': '/bin/bash', 02:29:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:00 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:00 INFO - 'USER': 'cltbld', 02:29:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:00 INFO - 'XPC_FLAGS': '0x0', 02:29:00 INFO - 'XPC_SERVICE_NAME': '0', 02:29:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:01 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 02:29:01 INFO - Cleaning up... 02:29:01 INFO - Return code: 0 02:29:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:01 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 02:29:01 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 02:29:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:01 INFO - 'HOME': '/Users/cltbld', 02:29:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:01 INFO - 'LOGNAME': 'cltbld', 02:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:01 INFO - 'MOZ_NO_REMOTE': '1', 02:29:01 INFO - 'NO_EM_RESTART': '1', 02:29:01 INFO - 'PAGER': '/bin/cat', 02:29:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:01 INFO - 'PWD': '/builds/slave/test', 02:29:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:01 INFO - 'SHELL': '/bin/bash', 02:29:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:01 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:01 INFO - 'USER': 'cltbld', 02:29:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:01 INFO - 'XPC_FLAGS': '0x0', 02:29:01 INFO - 'XPC_SERVICE_NAME': '0', 02:29:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:01 INFO - Downloading/unpacking psutil>=0.7.1 02:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:01 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 02:29:01 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 02:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:04 INFO - Installing collected packages: psutil 02:29:04 INFO - Running setup.py install for psutil 02:29:04 INFO - building 'psutil._psutil_osx' extension 02:29:04 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 02:29:05 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 02:29:05 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 02:29:05 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 02:29:05 INFO - building 'psutil._psutil_posix' extension 02:29:05 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 02:29:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:29:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:29:05 INFO - ^ 02:29:05 INFO - 1 warning generated. 02:29:05 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 02:29:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:05 INFO - Successfully installed psutil 02:29:05 INFO - Cleaning up... 02:29:05 INFO - Return code: 0 02:29:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:29:05 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')]} 02:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:05 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')]} 02:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:05 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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:05 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 02:29:05 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 02:29:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:05 INFO - 'HOME': '/Users/cltbld', 02:29:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:05 INFO - 'LOGNAME': 'cltbld', 02:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:05 INFO - 'MOZ_NO_REMOTE': '1', 02:29:05 INFO - 'NO_EM_RESTART': '1', 02:29:05 INFO - 'PAGER': '/bin/cat', 02:29:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:05 INFO - 'PWD': '/builds/slave/test', 02:29:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:05 INFO - 'SHELL': '/bin/bash', 02:29:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:05 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:05 INFO - 'USER': 'cltbld', 02:29:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:05 INFO - 'XPC_FLAGS': '0x0', 02:29:05 INFO - 'XPC_SERVICE_NAME': '0', 02:29:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:29:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:29:08 INFO - Installing collected packages: mozsystemmonitor 02:29:08 INFO - Running setup.py install for mozsystemmonitor 02:29:08 INFO - Successfully installed mozsystemmonitor 02:29:08 INFO - Cleaning up... 02:29:08 INFO - Return code: 0 02:29:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:08 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')]} 02:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:08 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')]} 02:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:08 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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:08 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 02:29:08 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 02:29:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:08 INFO - 'HOME': '/Users/cltbld', 02:29:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:08 INFO - 'LOGNAME': 'cltbld', 02:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:08 INFO - 'MOZ_NO_REMOTE': '1', 02:29:08 INFO - 'NO_EM_RESTART': '1', 02:29:08 INFO - 'PAGER': '/bin/cat', 02:29:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:08 INFO - 'PWD': '/builds/slave/test', 02:29:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:08 INFO - 'SHELL': '/bin/bash', 02:29:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:08 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:08 INFO - 'USER': 'cltbld', 02:29:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:08 INFO - 'XPC_FLAGS': '0x0', 02:29:08 INFO - 'XPC_SERVICE_NAME': '0', 02:29:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:08 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:08 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 02:29:08 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 02:29:08 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 02:29:08 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 02:29:08 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 02:29:08 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 02:29:11 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:11 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 02:29:11 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 02:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:12 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 02:29:12 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 02:29:12 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 02:29:12 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 02:29:12 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 02:29:12 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 02:29:12 INFO - Downloading docopt-0.6.1.tar.gz 02:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:12 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:12 INFO - Running setup.py install for blobuploader 02:29:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:12 INFO - Running setup.py install for requests 02:29:13 INFO - Running setup.py install for docopt 02:29:13 INFO - Successfully installed blobuploader requests docopt 02:29:13 INFO - Cleaning up... 02:29:13 INFO - Return code: 0 02:29:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:13 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')]} 02:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:13 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')]} 02:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:13 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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:13 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 02:29:13 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 02:29:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:13 INFO - 'HOME': '/Users/cltbld', 02:29:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:13 INFO - 'LOGNAME': 'cltbld', 02:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:13 INFO - 'MOZ_NO_REMOTE': '1', 02:29:13 INFO - 'NO_EM_RESTART': '1', 02:29:13 INFO - 'PAGER': '/bin/cat', 02:29:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:13 INFO - 'PWD': '/builds/slave/test', 02:29:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:13 INFO - 'SHELL': '/bin/bash', 02:29:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:13 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:13 INFO - 'USER': 'cltbld', 02:29:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:13 INFO - 'XPC_FLAGS': '0x0', 02:29:13 INFO - 'XPC_SERVICE_NAME': '0', 02:29:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:13 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-1wuJVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:13 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Ty6hqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:13 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-RnkaPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-EgShJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Jk8Dxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Py0LXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-UPc7Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-54sqWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-cYaaKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-ZL3n6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-JOvbEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:14 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-_3VYGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:15 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-ZdgbsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:15 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-d1xOgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:15 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-iBSiLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:15 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-jHNNhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:15 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-VoJi0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:15 INFO - Running setup.py install for manifestparser 02:29:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:15 INFO - Running setup.py install for mozcrash 02:29:15 INFO - Running setup.py install for mozdebug 02:29:15 INFO - Running setup.py install for mozdevice 02:29:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Running setup.py install for mozfile 02:29:16 INFO - Running setup.py install for mozhttpd 02:29:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Running setup.py install for mozinfo 02:29:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Running setup.py install for mozInstall 02:29:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:16 INFO - Running setup.py install for mozleak 02:29:16 INFO - Running setup.py install for mozlog 02:29:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:17 INFO - Running setup.py install for moznetwork 02:29:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:17 INFO - Running setup.py install for mozprocess 02:29:17 INFO - Running setup.py install for mozprofile 02:29:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:17 INFO - Running setup.py install for mozrunner 02:29:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:17 INFO - Running setup.py install for mozscreenshot 02:29:17 INFO - Running setup.py install for moztest 02:29:17 INFO - Running setup.py install for mozversion 02:29:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:18 INFO - Cleaning up... 02:29:18 INFO - Return code: 0 02:29:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:18 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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:18 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 02:29:18 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 02:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:18 INFO - 'HOME': '/Users/cltbld', 02:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:18 INFO - 'LOGNAME': 'cltbld', 02:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:18 INFO - 'MOZ_NO_REMOTE': '1', 02:29:18 INFO - 'NO_EM_RESTART': '1', 02:29:18 INFO - 'PAGER': '/bin/cat', 02:29:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:18 INFO - 'PWD': '/builds/slave/test', 02:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:18 INFO - 'SHELL': '/bin/bash', 02:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:18 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:18 INFO - 'USER': 'cltbld', 02:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:18 INFO - 'XPC_FLAGS': '0x0', 02:29:18 INFO - 'XPC_SERVICE_NAME': '0', 02:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:18 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-BE7InA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:18 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)) 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:18 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-yQL5p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:18 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)) 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:18 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-JRLRHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:18 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)) 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:18 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-MjWst0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:18 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)) 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:18 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Nj2O8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:18 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)) 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:18 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-lwxcGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-6JJtVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-ZYZu0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-36QKpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-m6NoD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-6aro5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-kzHcBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-i5v_wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Xcgpgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-LiUVO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:19 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)) 02:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:19 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-3iOPuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:20 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)) 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:20 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-f5wdL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:20 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)) 02:29:20 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)) 02:29:20 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)) 02:29:20 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)) 02:29:20 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)) 02:29:20 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)) 02:29:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:20 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 02:29:20 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 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:23 INFO - Downloading blessings-1.6.tar.gz 02:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:23 INFO - Installing collected packages: blessings 02:29:23 INFO - Running setup.py install for blessings 02:29:23 INFO - Successfully installed blessings 02:29:23 INFO - Cleaning up... 02:29:24 INFO - Return code: 0 02:29:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:24 INFO - Reading from file tmpfile_stdout 02:29:24 INFO - Current package versions: 02:29:24 INFO - blessings == 1.6 02:29:24 INFO - blobuploader == 1.2.4 02:29:24 INFO - docopt == 0.6.1 02:29:24 INFO - manifestparser == 1.1 02:29:24 INFO - mozInstall == 1.12 02:29:24 INFO - mozcrash == 0.17 02:29:24 INFO - mozdebug == 0.1 02:29:24 INFO - mozdevice == 0.48 02:29:24 INFO - mozfile == 1.2 02:29:24 INFO - mozhttpd == 0.7 02:29:24 INFO - mozinfo == 0.9 02:29:24 INFO - mozleak == 0.1 02:29:24 INFO - mozlog == 3.1 02:29:24 INFO - moznetwork == 0.27 02:29:24 INFO - mozprocess == 0.22 02:29:24 INFO - mozprofile == 0.28 02:29:24 INFO - mozrunner == 6.11 02:29:24 INFO - mozscreenshot == 0.1 02:29:24 INFO - mozsystemmonitor == 0.0 02:29:24 INFO - moztest == 0.7 02:29:24 INFO - mozversion == 1.4 02:29:24 INFO - psutil == 3.1.1 02:29:24 INFO - requests == 1.2.3 02:29:24 INFO - wsgiref == 0.1.2 02:29:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:24 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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:24 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 02:29:24 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 02:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:24 INFO - 'HOME': '/Users/cltbld', 02:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:24 INFO - 'LOGNAME': 'cltbld', 02:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:24 INFO - 'MOZ_NO_REMOTE': '1', 02:29:24 INFO - 'NO_EM_RESTART': '1', 02:29:24 INFO - 'PAGER': '/bin/cat', 02:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:24 INFO - 'PWD': '/builds/slave/test', 02:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:24 INFO - 'SHELL': '/bin/bash', 02:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:24 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:24 INFO - 'USER': 'cltbld', 02:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:24 INFO - 'XPC_FLAGS': '0x0', 02:29:24 INFO - 'XPC_SERVICE_NAME': '0', 02:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 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)) 02:29:24 INFO - Cleaning up... 02:29:24 INFO - Return code: 0 02:29:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:24 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 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29:24 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 02:29:24 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 02:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:24 INFO - 'HOME': '/Users/cltbld', 02:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:24 INFO - 'LOGNAME': 'cltbld', 02:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:24 INFO - 'MOZ_NO_REMOTE': '1', 02:29:24 INFO - 'NO_EM_RESTART': '1', 02:29:24 INFO - 'PAGER': '/bin/cat', 02:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:24 INFO - 'PWD': '/builds/slave/test', 02:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:24 INFO - 'SHELL': '/bin/bash', 02:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:24 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:24 INFO - 'USER': 'cltbld', 02:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:24 INFO - 'XPC_FLAGS': '0x0', 02:29:24 INFO - 'XPC_SERVICE_NAME': '0', 02:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:24 INFO - Downloading/unpacking jsonschema 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:27 INFO - Downloading/unpacking functools32 (from jsonschema) 02:29:27 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 02:29:27 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 02:29:27 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 02:29:27 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 02:29:27 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 02:29:27 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 02:29:27 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:27 INFO - warning: no files found matching '*.txt' 02:29:27 INFO - no previously-included directories found matching 'build' 02:29:27 INFO - no previously-included directories found matching 'dist' 02:29:27 INFO - no previously-included directories found matching '.git*' 02:29:27 INFO - Installing collected packages: jsonschema, functools32 02:29:27 INFO - Running setup.py install for functools32 02:29:28 INFO - warning: no files found matching '*.txt' 02:29:28 INFO - no previously-included directories found matching 'build' 02:29:28 INFO - no previously-included directories found matching 'dist' 02:29:28 INFO - no previously-included directories found matching '.git*' 02:29:28 INFO - Successfully installed jsonschema functools32 02:29:28 INFO - Cleaning up... 02:29:28 INFO - Return code: 0 02:29:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b78aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffeca5550e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, '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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 02:29: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', 'flake8'] in /builds/slave/test/build 02:29: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 flake8 02:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:28 INFO - 'HOME': '/Users/cltbld', 02:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:28 INFO - 'LOGNAME': 'cltbld', 02:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:28 INFO - 'MOZ_NO_REMOTE': '1', 02:29:28 INFO - 'NO_EM_RESTART': '1', 02:29:28 INFO - 'PAGER': '/bin/cat', 02:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:28 INFO - 'PWD': '/builds/slave/test', 02:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:28 INFO - 'SHELL': '/bin/bash', 02:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:29:28 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:28 INFO - 'USER': 'cltbld', 02:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:28 INFO - 'XPC_FLAGS': '0x0', 02:29:28 INFO - 'XPC_SERVICE_NAME': '0', 02:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:28 INFO - Downloading/unpacking flake8 02:29:28 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 02:29:28 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 02:29:28 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 02:29:28 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 02:29:28 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 02:29:28 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 02:29:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:29:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:29:30 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 02:29:30 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 02:29:30 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 02:29:30 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 02:29:30 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 02:29:30 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 02:29:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:29:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:31 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 02:29:31 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 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:31 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 02:29:31 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 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:29:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:29:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:29:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:29:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:29:32 INFO - no previously-included directories found matching 'docs/_build' 02:29:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:29:32 INFO - Running setup.py install for pep8 02:29:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:29:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:29:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:29:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:29:32 INFO - no previously-included directories found matching 'docs/_build' 02:29:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:29:32 INFO - Cleaning up... 02:29:32 INFO - Return code: 0 02:29:32 INFO - Running post-action listener: _resource_record_post_action 02:29:32 INFO - Running post-action listener: _start_resource_monitoring 02:29:32 INFO - Starting resource monitoring. 02:29:32 INFO - ##### 02:29:32 INFO - ##### Running install step. 02:29:32 INFO - ##### 02:29:32 INFO - Running pre-action listener: _resource_record_pre_action 02:29:32 INFO - Running main action method: install 02:29:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:32 INFO - Reading from file tmpfile_stdout 02:29:32 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:32 INFO - Reading from file tmpfile_stdout 02:29:32 INFO - Output received: 02:29:32 INFO - Usage: mozinstall [options] installer 02:29:32 INFO - Options: 02:29:32 INFO - -h, --help show this help message and exit 02:29:32 INFO - -d DEST, --destination=DEST 02:29:32 INFO - Directory to install application into. [default: 02:29:32 INFO - "/builds/slave/test"] 02:29:32 INFO - --app=APP Application being installed. [default: firefox] 02:29:32 INFO - mkdir: /builds/slave/test/build/application 02:29:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:30:07 INFO - Reading from file tmpfile_stdout 02:30:07 INFO - Output received: 02:30:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:30:07 INFO - Running post-action listener: _resource_record_post_action 02:30:07 INFO - ##### 02:30:07 INFO - ##### Running run-tests step. 02:30:07 INFO - ##### 02:30:07 INFO - Running pre-action listener: _resource_record_pre_action 02:30:07 INFO - Running main action method: run_tests 02:30:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:07 INFO - 2016-04-26 02:30:07.020 screenresolution[1639:7447] starting screenresolution argv=screenresolution get 02:30:07 INFO - 2016-04-26 02:30:07.023 screenresolution[1639:7447] Display 0: 1600x1200x32@50 02:30:07 INFO - 2016-04-26 02:30:07.029 screenresolution[1640:7449] starting screenresolution argv=screenresolution list 02:30:07 INFO - Available Modes on Display 0 02:30:07 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 02:30:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:07 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 02:30:07 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 02:30:07 INFO - Graphics/Displays: 02:30:07 INFO - Intel Iris: 02:30:07 INFO - Chipset Model: Intel Iris 02:30:07 INFO - Type: GPU 02:30:07 INFO - Bus: Built-In 02:30:07 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:07 INFO - Vendor: Intel (0x8086) 02:30:07 INFO - Device ID: 0x0a2e 02:30:07 INFO - Revision ID: 0x0009 02:30:07 INFO - Displays: 02:30:07 INFO - Display: 02:30:07 INFO - Resolution: 1600 x 1200 @ 50 Hz 02:30:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:07 INFO - Display Serial Number: 1600x1200 50 02:30:07 INFO - Main Display: Yes 02:30:07 INFO - Mirror: Off 02:30:07 INFO - Online: Yes 02:30:07 INFO - Rotation: Supported 02:30:07 INFO - Return code: 0 02:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:30:07 INFO - Python 2.7.3 02:30:07 INFO - Return code: 0 02:30:07 INFO - grabbing minidump binary from tooltool 02:30: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')]} 02:30:07 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 0x10b629e00>, '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 0x10b452db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b624b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:30:07 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 02:30:07 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 02:30:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:30:07 INFO - Return code: 0 02:30:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:30:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:30:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:30:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:30:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:30:08 INFO - Return code: 0 02:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 02:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0017 --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 02:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Knjc4ApWB6/Render', 02:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:30:08 INFO - 'HOME': '/Users/cltbld', 02:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:30:08 INFO - 'LOGNAME': 'cltbld', 02:30:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:30:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:08 INFO - 'MOZ_NO_REMOTE': '1', 02:30:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:30:08 INFO - 'NO_EM_RESTART': '1', 02:30:08 INFO - 'PAGER': '/bin/cat', 02:30:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:08 INFO - 'PWD': '/builds/slave/test', 02:30:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:30:08 INFO - 'SHELL': '/bin/bash', 02:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YEW6AzQDoj/Listeners', 02:30:08 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 02:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:30:08 INFO - 'USER': 'cltbld', 02:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:08 INFO - 'XPC_FLAGS': '0x0', 02:30:08 INFO - 'XPC_SERVICE_NAME': '0', 02:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:30:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 02:30:08 INFO - mozversion application_buildid: 20160426013609 02:30:08 INFO - mozversion application_changeset: 99fbe8621fa73724446ea9f828a0d30642faa6f9 02:30:08 INFO - mozversion application_display_name: Nightly 02:30:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:30:08 INFO - mozversion application_name: Firefox 02:30:08 INFO - mozversion application_remotingname: firefox 02:30:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:30:08 INFO - mozversion application_vendor: Mozilla 02:30:08 INFO - mozversion application_version: 49.0a1 02:30:08 INFO - mozversion platform_buildid: 20160426013609 02:30:08 INFO - mozversion platform_changeset: 99fbe8621fa73724446ea9f828a0d30642faa6f9 02:30:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:30:08 INFO - mozversion platform_version: 49.0a1 02:30:08 INFO - using testdate: 1461663008 02:30:08 INFO - actual date: 1461663008 02:30:08 INFO - starting webserver on 'localhost:49270' 02:30:08 INFO - SUITE-START | Running 2 tests 02:30:08 INFO - TEST-START | dromaeo_css 02:30:08 INFO - operating with platform_type : mac_ 02:30:08 INFO - Initialising browser for dromaeo_css test... 02:30:08 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpmyN50K/profile http://localhost:49270/getInfo.html) 02:30:11 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 02:30:11 INFO - PROCESS | 1658 | colorDepth:24 02:30:11 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 02:30:11 INFO - PROCESS | 1658 | __metrics 02:30:11 INFO - PROCESS | 1658 | 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" 02:30:11 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:30:11 INFO - TEST-INFO | 1658: exit 0 02:30:11 INFO - Browser initialized. 02:30:11 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:11 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpmyN50K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:23 INFO - PROCESS | 1663 | RSS: Main: 239968256 02:30:23 INFO - PROCESS | 1663 | 02:31:15 INFO - PROCESS | 1663 | 1461663075508 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] 02:33:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 02:33:27 INFO - PROCESS | 1663 | RSS: Main: 362926080 02:33:27 INFO - PROCESS | 1663 | 02:36:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 02:36:31 INFO - PROCESS | 1663 | RSS: Main: 377171968 02:36:31 INFO - PROCESS | 1663 | 02:39:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 02:39:34 INFO - PROCESS | 1663 | RSS: Main: 379785216 02:39:34 INFO - PROCESS | 1663 | 02:42:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 02:42:37 INFO - PROCESS | 1663 | RSS: Main: 381689856 02:42:37 INFO - PROCESS | 1663 | 02:45:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 02:45:41 INFO - PROCESS | 1663 | RSS: Main: 352018432 02:45:41 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 02:48:44 INFO - PROCESS | 1663 | RSS: Main: 420429824 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | __start_tp_report 02:48:44 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 02:48:44 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 02:48:44 INFO - PROCESS | 1663 | |i|pagename|runs| 02:48:44 INFO - PROCESS | 1663 | |0;dojo.html;3384.204711892705;3413.92461374667;3442.0706409269496;3463.6709512596294;3485.21582643905;5500.3949565547755;5618.943810561893;5680.517156041737;5699.373068962416;5703.657780533165;5579.69311687857;5630.662160270386;5631.0708733059;5635.5773317001185;5646.971765141168;4226.577342265779;5069;5107;5118.07874582575;5121.8463446096675;5133.6919784813035;5147.691138531682;5162.8193013244545;5169.250458683488;5175.689458632475;5110.182370820669;5152.845414637567;5153.922691159635;5200.557952574017;5212.687238765668;5188.999999999981;5198.896022079575;5201.323827902386;5220.321358223445;5264.31563896691;4994.225894986246;5014.598832093424;5099.59203263738;5117.974410127963;5140.280360749498;5448.99999999996;5494;5502.80740174096;5504.972475137639;5606.35526914406;6180.969095154541;6196.318404975449;6197.969010154966;6208.286047104596;6211;5383.703896285702;5952.315483719384;6127.433875357673;6194.318624951296;6196.690165491753;5698.82903512887;5716.399778023331;5725.627834190765;5760.36635970043;5791.855203619901;6206.596571222856;6259.593126446857;6276.623402595859;6290.087937249074;6295.087212354183;6003.729832157476;6103.725332360054;6109.572329936951;6115.694215289218;6129.724162412702;5128.8461346159675;6135.60015773269;6215.098810672427;6218.968905155445;6223.471004964629;6117.857853872152;6118.265808102967;6138.232720909887;6157.96921015392;6163.859685958112;6129.203203583595;6191.133241346215;6192.287886892975;6239.750409983641;6250.843728906813;4911.950880491149;4930;4935.161022626162;4944.332515110486;4945.851624451272;4351.630111440563;4433.866983990485;4448.888777780517;4470.977645111753;4490.101979604092;5576.414476479992;5606.6916319602;5613.270274864241;5615.017371959891;5619.325680918234;6169.784057557964;6201.596896201732;6212.1303017577575;6226.688665566703;6237.906431403532;5562.749676264578;5618.43815618443;5632.690202038927;5651.660900345993;5670.092785154356;5325.3875804282225;5342.5993050521365;5366.195070739343;5368.328958880062;5370.167624018283;6282.434580887742;6308.968455157695;6309.747610095545;6312.116303717483;6320.336364681734;6100.914243738862;6200.131981522501;6235.781747638811;6286.497080233662;6290.308066112632;6326.8734625306315;6331.366863313632;6346.492280617632;6347.39699728525;6354.936450635434;6126.969365153324;6146.815595532141;6150.138980542638;6160.291566469913;6174.38256174379;6102.938970610237;6139.461473530676;6183.876322473436;6228.5951413158;6281.214848143982;5680.517156041783;5721.141828725725;6091.116788065794;6091.543134265036;6123.938760612336;5875.177475153397;6018.187544681412;6047.969760151171;6070.817875463743;6076.361981991828;5692.430756924275;5786.392428794916;5799.536037117105;5888.587798854039;5894.381089985662;6007;6017.06735456005;6054.455099041107;6076.939230607814;6078.6048906820915;5895.056790913433;5904.114382842608;5918.082697181943;5928.762849485972;5944.613600115978 02:48:44 INFO - PROCESS | 1663 | |1;ext.html;490.3306985964158;511.09791218499356;522.8274669359107;525.7318767428611;560.4479587606207;31186.376272474536;31933.36133277333;38511.22977540451;39539;39559;43871.78064109675;44626;44633.107337853224;44696.99999999992;44726.32910506337;8999.280057595393;9788.608455661764;9798.41209527427;9805.264605154607;9809.362391444532;8396.916030839675;8605.698800541983;8621.094785047542;11176.21766476347;11475.827862582068;11557.653270401901;11805.996490298336;11822.408879556031;11828.349440780754;11851.881481185206;11657.825132622975;11853.822192667118;11856.458660374095;11857.229280096766;11860.11049171307;8397.580120993987;11128.721781955435;11392;11398.544058237661;11399.601013964471;38313.61686383128;38840.80579597112;38896.22207555862;38903.610963890285;38926.44294228227;3130.248740302311;3365.3437579671972;3423.1099914022284;3450.516927630134;3754.699624030071;28165.718342816515;29450.70549294501;29482.705172948426;29499;29565.817367305495;2638.630591717161;2668.5997100434897;2807.2280122966145;2820.2808283887475;2825.5620378841313;354340;415394;415761;416775.7483637748;417475;62501.687491562254;65302.34697653058;66130.0080498793;66254.33745662578;66264.33735662678;64305.678471607345;64427.10078046499;64428.35571644317;64433.35566644368;64561.677191613744;16797.496075117768;17155;17156.31374745021;17157.57106072333;17165.485035448957;11592.6522204334;13077.869221307665;13163.53927612548;13184.73630527384;13190.208587484782;15767.448139315069;16458.83541164597;16863;18917.810821891606;19416.611667766567;6814.897776533356;7730.57481838493;7806.843863122819;8033.678652853937;8101.096242383035;912.2473958983865;954.5704433005176;960.4669408478362;992.6575331510618;1118.4072441605963;29390.11829645114;29500.704992950225;29645.407091858044;29656.851715741715;29782.851085744867;1046.1160319529986;1056.3239526702916;1099.3623698254994;1131.9943400282946;1167.3754541320523;15428.460003899809;15502.9224853875;15523.767143492856;15662.981906176063;15756.842431575538;643450.5654943391;654245;661076;661203;662880;60469.395306046375;61118.694406527684;61157.08264376038;61159.08261376083;61160.08259876105;1310.1876836361516;1440.4598275646424;1480.726065677853;1486.1900264355736;1492.783546385768;28971.7102828969;29109.41781164407;29124.417511650074;29832.10503684893;29843.10470685883;47627.76186119047;48343.5165648339;48576;48653.27020094772;48770;383745;384564;384768.07615962863;385016;385547;22583.88708056449;25571;26125.346866327734;26374.340641484116;26437.810298536995;42878.78560607177;43918;44278.55721442744;44632.77683611561;44859.55140448684;35812;36059.81970090238;36697.81651091728;36902.26195476129;37047.81476092602;35204.76783312572;37023;37132.44301335536;38134.23731525298;38199.236015278984;36641.63358366489;37028.81485592555;37229;38298.42552361773;38307.425388618634;29087.12738617845;31008.53487197739;32683.836580816944;33182.5022624656;33744.15639609029;24598.049880737562;24693.259202223962;24730.752692473565;25261.368465788502;25414.872925635253 02:48:44 INFO - PROCESS | 1663 | |2;jquery.html;361.69436825882127;374.90153849221775;394.01692776522583;397.60636969400304;406.32549967054695;19186;20664.276750313744;20694.586108277843;20768.06543705533;21983.780162198374;21016.684749728767;21087.367378978655;22085.116595336167;22120.446988825286;22214.777852221472;5285.762140703678;5841.3282472515675;6177.227846519185;6185.319614842364;6192.78325258616;3985.4818873546396;4230;5865.912011319833;6142.570020098595;6192.318844927055;6153.476954458876;6182.8454288642915;6186.7525298987985;6186.81439556814;6191.040388739752;6096.2074930259005;6204.689765511729;6207;6210.968945155268;6222.0355844844125;3878.1855810279876;4956.975215123937;5802.520357308901;6018.3981601839905;6037.969810150965;12897.548585799452;13373.130746501447;13445.529406470727;13581.796273055912;13592.11651242666;1957.7552805899263;2016.6269240190466;2153.7630860605323;2740.3937015706238;2775.8056936014486;9360.906390936072;9896.604135834557;9951.70144895647;9957.153641940444;9961.203103751683;1656.2546853915785;1689.3158270900306;1695.6015336395938;1710.0509217384347;1712.1781544858538;93420.53289733508;124151;128364;132492;133275.6672433273;11535.711607209887;11765.705857353463;11770.52917883275;11797.52809887612;11876.703082423008;15182;15277.541673749805;15291.770623440658;15304.38782448712;15305.933698950721;8618.612162452704;8680.131986801269;8695.56631763943;8775;8884.289256859436;8671.4363566368;8684.956575217084;8688.522131282714;8770.473771573726;8796.020895298634;3945.704072194597;4012.996750812297;4531.478879928851;4694.319323698045;4729.716217026989;1542.537238828347;1812.238859678938;1828.8479943666657;1941.5728539721522;2008.9095990680455;850.6342272822745;913.5885057586046;917.499962520428;987.3878195518824;1069.6363236499628;20384;20543.76737395731;20587.79412205859;20735;20844.895775521025;935.934484586086;949.4161090929167;970.5875003123645;971.7949742319473;989.1839232633107;9410.247180225566;10934.56261749537;10938.890611093946;11292.943535282435;11319.207655464039;613042;620485;624555;631605;633984;128082;134648.9628048989;136010.71084223044;137781;138025.309873452;931.8509038553799;940.5109343141528;985.1626117799813;988.3328753091635;1001.9899801001897;16900.746488802924;18553.25786968506;18911.621767564295;19078.236870525023;19517.12172952249;52441;54750.17874731962;55544.72227638836;55846.44153558413;56635.58411039757;73344.26655733588;74116.25883741309;81464;81748.59125704333;84183;11476.368799716096;11755.294682319089;12104.213226140273;12178.939105304416;12309.692257693629;12363.93818030904;13371.799423008468;13394.330283485982;13422.261775602436;13779.448822047407;11405;11440.771184576428;11682.182247242612;11780.175387722777;11847.526098955945;9456.669016584387;11428.82856757166;11445.313281203154;11561.479733411848;11750.58872939443;11447.25592836463;11614.12894032934;11824.054075674097;11870.881291186977;12143.39283035827;11944.64166075019;12018.879811201776;12194.51221951112;12254.571090011807;12262.20295680778;10670.73323166927;11634.76730465403;11848.407579621156;11941.880581194077;11960.102992275788 02:48:44 INFO - PROCESS | 1663 | |3;mootools.html;1415.4267521653733;1588.5631451350878;1594.25070216998;1841.5304097455146;1968.075004747768;7800.258975397335;8597.26923211527;8801.91198088019;8819;8838.823223535534;8435.789105272372;8660.047394786568;8670;8682.696105636303;8690.130986901308;2124.893755312236;2365.6215005599106;2408.50625621748;2439.9268021959283;2460.5694003549397;2457.803375729937;2481.813863960201;2487.987560062197;2489.352768280251;2493.4265119022634;2366.1363602285214;2442.2306973303444;2442.767937045987;2451.1053465485147;2461.310832966772;1755.49090763679;2325.2210509479346;2400.1839374612714;2408.1330720940446;2423.09134074722;2108.652072408051;2430.2587710748217;2430.7447717989535;2437.110454684045;2440.9511809763885;8286.212809783061;8353.33173346131;8365.95817020911;8372.120927302667;8429;5214.139666954948;5221.765020574083;5415.268938693265;5477.20580515823;5479.643823151482;3538.982305088458;3595.94606080909;3658.432942893829;3661.0847288177956;3667.6149004354693;1030.7165529479378;1052.4737631184407;1077.8760442549128;1081.707938856512;1086.1093902999544;12171.756564868741;12185.086118541145;12188.756224875542;12191.939040304831;12196.207246528948;4799.568038876448;4863.562279394871;4986.8753281168265;5094.580074464674;5095.566876815512;7850.214978502218;9327;9405;9421.576029078708;9457.90542094584;4727.172744769651;5319.1223448131395;5402.189671549299;5402.297701298885;5404.405515393303;4434.357018232338;4484.865454036384;4596.931046034312;4849.466558678542;5020.322256495399;3529.5235143255845;3640.5449318835144;3831.5593706723525;3909.7654140751647;3937.547182074041;1211.9939400302942;1214.9878501214876;1215.5198696514826;1224.185916365607;1227.9324637144864;803.843250566134;855.5422848776;896.3098414221014;915.3226612306845;947.6091928983739;5759.6832174229985;5768.855778605484;6029.849253768691;6104.023356262976;6113.480354169857;449.38658730832833;451.345548954018;457.95878370946775;983.7294743945472;1006.2603986070233;3842.3852183650483;3897.538423091341;3968.8214030368704;3970.960290397059;4000.7199496035;10321.587136514605;10406.739831504121;10850.023497885228;10867.945660271806;10876.891231087588;10625.9468702656;10817.080548153337;10828.350298982086;10835.079018283377;10870.402127882884;8004.359651227888;8087.029556453145;8291.626876790559;8301.46040507365;8378.58107094655;5829.708514574169;5915.947575910515;5966.880662386815;6000.669963152069;6013.278406591062;9972.700818975442;10262.897371026194;10274.33216840903;10284.588616455258;10289.794204116026;9672.984336644551;9910.454924979196;10178.592856285963;10349.223808214263;10357;6486.3513648636645;6735.090762746966;6935.791926242221;7087.787366379017;7252.238514956065;7027.964860175666;8332.958335208285;8363.707270245512;8466.74599762008;8501.447405918596;7353.485256032026;7426.628668566658;7452.143003554444;7591.3547348475995;7596.08846938371;7208.243134471015;7238.022866912899;7280.453965952681;7316.707331706885;7472.850542989001;6936.271691472322;7125.429965602843;7140.9285907140265;7143.428525718034;7150.713971441084;4867.31172407605;4946.258061290835;4977.129002424489;5056.367954005749;5112.71880046601;4682.859514214579;4873.51264873524;5033.421156567042;5080.745962701776;5108.540231379194 02:48:44 INFO - PROCESS | 1663 | |4;prototype.html;313.1357453428537;313.9497680371141;319.6563694028919;322.0467416447316;326.0284352629167;4457.9554204457945;4560.270356742922;4770.9761451192735;5313.7947751683005;5367;4276.700630955835;5287.048331300364;5288.629795914288;5330.733463326837;5342.278792363029;3856.5757766645647;3896.4350169225445;3935.3703407454814;4095.75425474471;4114.341705327149;3388.9833050085;3998.6001399860074;4052.696047796412;4058.492688413948;4063.9796801015955;3701.4262789267705;4024.3359845625437;4041.6766658667234;4050.5949405059555;4075.4498142750795;3725.2363265530503;3818.369968955119;3825.190961171247;3954.3079961006997;4037.6164264394833;3483.3729928613093;3495.5455790747296;3497.720182385403;3504;3542.5394698689006;4028.174224284044;4304.053108316165;4851.345068415754;5000.824971125993;5025.0954828130925;4104.665983555356;4217.304144816102;4321.675874309408;4612.58486736192;4620.145273124483;5217.399999000126;5459.181122831567;5490.258815059956;5507.614466987313;5537.833864984057;3883.339832228528;3902.8829135125707;3903.9219215615813;3905.648491635738;3910.1593157470998;5575.721213939287;5753.741081651294;5805.993864233707;5842.970785146047;5868.794592189253;3691.8707845225827;3895.941560876589;3931.233409485123;3935.35066713989;3967.6032396760093;3251.5935508061493;3983.42240375144;4020.4974378202724;4041.939370909439;4045.211183819186;3848.1913500654646;4000.9199816003515;4008.599140085968;4503.797329120198;4579.862604121881;4497.3254011897825;4891.315215869781;5262.578993680497;5591.30108736408;5648.63283886546;1387.7779555271109;1410.8307003159687;1433.7419264532275;1436.2029073863896;1439.820022497188;913.8622415093118;938.1275413865035;960.3901522533241;962.9807403851884;965.7633879699519;4043.553650041741;4377.387165796791;4945.604351651859;5036.471170527042;5217.043608646757;5768.846230753782;5781.184418283912;5789.3052833659385;5807.722301093752;5843.561732870052;4539.591436770706;4793.448753393334;5171.172612382001;5220.738963051832;5243.879891464572;3029.8939537116094;3060.403221371811;3169.6354919184128;3283.3925723741186;3296.6373698893103;5536.4186760390385;6452.516061295421;6570.868582628321;6592.538522303488;6606.7026983785845;5829.446202610745;5887.381824908409;6146.496496996759;6350.571121497718;6395.232572091379;5726.856828579319;5947.1079338099635;6045.579288867113;6465.418112369909;6599.241087274832;5972.343042265435;6239.688015599111;6271.40079279799;6297.1813664223355;6300;4877.926831097466;5708.457696518824;6182.938170618416;6196.287426945959;6205.596636218631;4989.7525618595355;5650.78508120756;6390.265119511129;6540.771073012422;6543.509236807164;3667.028237517007;4054.574269701639;4062.1923244157247;4069.491313585802;4099;5455.918161227664;5576.609637325349;6077.513798896166;6247.562670613195;6265.310815810349;4897.804087836589;5002.424721157114;6035.69821508914;6048.274207095177;6061.878762424815;5443.564514838724;5974.372690867396;5979.162917191684;5981.671008094422;6015.218021657157;5171.198464238049;5650.717464126859;5872.354041055395;5907.556933229939;5930.436608522184;4542.977285113553;4595.701279416827;5027.441493137139;5063.87340316495;5483.4516548344845;4876.244182151772;5004.999000200048;5109.795608175631;5280.947190528045;5544.584156188222 02:48:44 INFO - PROCESS | 1663 | |5;yui.html;635.2123367024889;636.1539152926607;701.5755467941894;721.5310048468498;725.5139056831925;14258.358373873201;14908.776368354469;15110.471133510302;15435.69128617427;15453;14163.858361416384;14224.71550568988;14301.998860079773;14336.71326573468;14343.426262949495;710.77610552676;733.7395224695236;884.5709830732089;900.7658008917663;918.6646873891049;924.3714274293478;924.9213816825578;931.7717159295981;931.9161275485205;936.929730270229;979.9608015679399;984.6455276100598;984.6455276100598;984.8375018122001;985.2512090810964;739.8557281330143;765.2041876448543;767.8656235158825;913.4610579757974;977.4037836919473;705.9647017649148;708.0087876972281;708.6456771614193;709.4572651921285;710.8507213485175;15346.539603811903;15428;15430.228488575638;15434.996725212937;15444.5366639001;12344.259344439362;12430.627081187578;12447.875521244852;12561.371931403393;12745.681357966032;9973.900260997443;10127.341722787995;10316.690499284958;10648.201384896087;10774.138068954464;3041.635003799536;3487.7732947358595;3499.4575840744724;3576.928461430783;3602.2075143468396;73184.26815731882;76984.99999999945;88801.55599221963;88853;88885.55557222173;9330.312335410328;9452.480113593818;9580.041995800504;9881.80236395268;10024.097831195228;21211.257605983716;21240.575188496143;21243.46891327698;21255.36233912985;21334.466638334165;7346.33882950537;7510.061242344707;7621.961890190513;7623.885641715378;7685.269899359552;3992.3412636915164;4010.7393019453643;4035.9192816143513;4079.5104587449696;4148.647364973951;3537.7700449471217;3627.782333060025;3627.818609069536;3628.691561217326;3628.8004159771467;520.3287758791192;629.2511910826103;635.9236891573041;641.1696852575925;643;547.625460095164;555.7304707216997;563.5463451921242;598.4404581716109;598.6348327520209;7948;8118;8130.308923741548;8258;8389.874151887727;532.6750682083925;546.3498436860124;580.274656679151;589.1604463639297;601.227422761749;3801.125741079512;3833.5208098987628;3851.268259030775;3939.7045221608496;4067.7355971861734;93371.53314233385;93907.91355474293;94009.58985615222;94679;100200;77498;77699.22300777033;77912;78694;81877;68074;69274.65362673154;73666;75448.24551754413;76711.61644191743;7367.373773229336;7729.652165652672;8265.83468330618;8297.668093276201;8533.232009119209;42565;42811.571884282006;43104.56895431005;44777.55222447734;45387.234340393785;64258.357416427105;64300.678496607215;64347.24353942768;65232;65809.01286480803;11139.220254582102;11383.658490245305;11396;11457.885421145682;11467.541298347973;11997.340146291688;12351.135420520477;12531.310777907303;12850.67873303175;13184.406701698255;11613.825792612948;11816.231944923553;11859.703507412383;11894.583689570823;11986.46060927243;10842.024217820433;10927.235093543375;11073.892213188106;11388.316700997966;11493.770124597295;10797.946010269898;11285.887141128484;11318.037966772916;11448.313101213955;12379.62861114168;9385.249180065715;9561.21546464379;9787.314887957775;9797.21622270231;9956.402615843073;8289.751307460785;9486.525673716149;9730.610775568897;9810.607575697177;9879.456629885426 02:48:44 INFO - PROCESS | 1663 | __end_tp_report 02:48:44 INFO - PROCESS | 1663 | __start_cc_report 02:48:44 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1225 02:48:44 INFO - PROCESS | 1663 | __end_cc_report 02:48:44 INFO - PROCESS | 1663 | __startTimestamp1461664124935__endTimestamp 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | ------- Summary: start ------- 02:48:44 INFO - PROCESS | 1663 | Number of tests: 6 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5680.67 Median:5875.18 stddev:633.63 (10.8%) stddev-sans-first:609.42 02:48:44 INFO - PROCESS | 1663 | Values: 3384.2 3413.9 3442.1 3463.7 3485.2 5500.4 5618.9 5680.5 5699.4 5703.7 5579.7 5630.7 5631.1 5635.6 5647.0 4226.6 5069.0 5107.0 5118.1 5121.8 5133.7 5147.7 5162.8 5169.3 5175.7 5110.2 5152.8 5153.9 5200.6 5212.7 5189.0 5198.9 5201.3 5220.3 5264.3 4994.2 5014.6 5099.6 5118.0 5140.3 5449.0 5494.0 5502.8 5505.0 5606.4 6181.0 6196.3 6198.0 6208.3 6211.0 5383.7 5952.3 6127.4 6194.3 6196.7 5698.8 5716.4 5725.6 5760.4 5791.9 6206.6 6259.6 6276.6 6290.1 6295.1 6003.7 6103.7 6109.6 6115.7 6129.7 5128.8 6135.6 6215.1 6219.0 6223.5 6117.9 6118.3 6138.2 6158.0 6163.9 6129.2 6191.1 6192.3 6239.8 6250.8 4912.0 4930.0 4935.2 4944.3 4945.9 4351.6 4433.9 4448.9 4471.0 4490.1 5576.4 5606.7 5613.3 5615.0 5619.3 6169.8 6201.6 6212.1 6226.7 6237.9 5562.7 5618.4 5632.7 5651.7 5670.1 5325.4 5342.6 5366.2 5368.3 5370.2 6282.4 6309.0 6309.7 6312.1 6320.3 6100.9 6200.1 6235.8 6286.5 6290.3 6326.9 6331.4 6346.5 6347.4 6354.9 6127.0 6146.8 6150.1 6160.3 6174.4 6102.9 6139.5 6183.9 6228.6 6281.2 5680.5 5721.1 6091.1 6091.5 6123.9 5875.2 6018.2 6048.0 6070.8 6076.4 5692.4 5786.4 5799.5 5888.6 5894.4 6007.0 6017.1 6054.5 6076.9 6078.6 5895.1 5904.1 5918.1 5928.8 5944.6 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:62892.04 Median:35935.91 stddev:132915.73 (369.9%) stddev-sans-first:133206.02 02:48:44 INFO - PROCESS | 1663 | Values: 490.3 511.1 522.8 525.7 560.4 31186.4 31933.4 38511.2 39539.0 39559.0 43871.8 44626.0 44633.1 44697.0 44726.3 8999.3 9788.6 9798.4 9805.3 9809.4 8396.9 8605.7 8621.1 11176.2 11475.8 11557.7 11806.0 11822.4 11828.3 11851.9 11657.8 11853.8 11856.5 11857.2 11860.1 8397.6 11128.7 11392.0 11398.5 11399.6 38313.6 38840.8 38896.2 38903.6 38926.4 3130.2 3365.3 3423.1 3450.5 3754.7 28165.7 29450.7 29482.7 29499.0 29565.8 2638.6 2668.6 2807.2 2820.3 2825.6 354340.0 415394.0 415761.0 416775.7 417475.0 62501.7 65302.3 66130.0 66254.3 66264.3 64305.7 64427.1 64428.4 64433.4 64561.7 16797.5 17155.0 17156.3 17157.6 17165.5 11592.7 13077.9 13163.5 13184.7 13190.2 15767.4 16458.8 16863.0 18917.8 19416.6 6814.9 7730.6 7806.8 8033.7 8101.1 912.2 954.6 960.5 992.7 1118.4 29390.1 29500.7 29645.4 29656.9 29782.9 1046.1 1056.3 1099.4 1132.0 1167.4 15428.5 15502.9 15523.8 15663.0 15756.8 643450.6 654245.0 661076.0 661203.0 662880.0 60469.4 61118.7 61157.1 61159.1 61160.1 1310.2 1440.5 1480.7 1486.2 1492.8 28971.7 29109.4 29124.4 29832.1 29843.1 47627.8 48343.5 48576.0 48653.3 48770.0 383745.0 384564.0 384768.1 385016.0 385547.0 22583.9 25571.0 26125.3 26374.3 26437.8 42878.8 43918.0 44278.6 44632.8 44859.6 35812.0 36059.8 36697.8 36902.3 37047.8 35204.8 37023.0 37132.4 38134.2 38199.2 36641.6 37028.8 37229.0 38298.4 38307.4 29087.1 31008.5 32683.8 33182.5 33744.2 24598.0 24693.3 24730.8 25261.4 25414.9 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:36165.97 Median:21052.03 stddev:104339.16 (495.6%) stddev-sans-first:104597.22 02:48:44 INFO - PROCESS | 1663 | Values: 361.7 374.9 394.0 397.6 406.3 19186.0 20664.3 20694.6 20768.1 21983.8 21016.7 21087.4 22085.1 22120.4 22214.8 5285.8 5841.3 6177.2 6185.3 6192.8 3985.5 4230.0 5865.9 6142.6 6192.3 6153.5 6182.8 6186.8 6186.8 6191.0 6096.2 6204.7 6207.0 6211.0 6222.0 3878.2 4957.0 5802.5 6018.4 6038.0 12897.5 13373.1 13445.5 13581.8 13592.1 1957.8 2016.6 2153.8 2740.4 2775.8 9360.9 9896.6 9951.7 9957.2 9961.2 1656.3 1689.3 1695.6 1710.1 1712.2 93420.5 124151.0 128364.0 132492.0 133275.7 11535.7 11765.7 11770.5 11797.5 11876.7 15182.0 15277.5 15291.8 15304.4 15305.9 8618.6 8680.1 8695.6 8775.0 8884.3 8671.4 8685.0 8688.5 8770.5 8796.0 3945.7 4013.0 4531.5 4694.3 4729.7 1542.5 1812.2 1828.8 1941.6 2008.9 850.6 913.6 917.5 987.4 1069.6 20384.0 20543.8 20587.8 20735.0 20844.9 935.9 949.4 970.6 971.8 989.2 9410.2 10934.6 10938.9 11292.9 11319.2 613042.0 620485.0 624555.0 631605.0 633984.0 128082.0 134649.0 136010.7 137781.0 138025.3 931.9 940.5 985.2 988.3 1002.0 16900.7 18553.3 18911.6 19078.2 19517.1 52441.0 54750.2 55544.7 55846.4 56635.6 73344.3 74116.3 81464.0 81748.6 84183.0 11476.4 11755.3 12104.2 12178.9 12309.7 12363.9 13371.8 13394.3 13422.3 13779.4 11405.0 11440.8 11682.2 11780.2 11847.5 9456.7 11428.8 11445.3 11561.5 11750.6 11447.3 11614.1 11824.1 11870.9 12143.4 11944.6 12018.9 12194.5 12254.6 12262.2 10670.7 11634.8 11848.4 11941.9 11960.1 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5683.61 Median:4909.89 stddev:3198.79 (65.2%) stddev-sans-first:3191.68 02:48:44 INFO - PROCESS | 1663 | Values: 1415.4 1588.6 1594.3 1841.5 1968.1 7800.3 8597.3 8801.9 8819.0 8838.8 8435.8 8660.0 8670.0 8682.7 8690.1 2124.9 2365.6 2408.5 2439.9 2460.6 2457.8 2481.8 2488.0 2489.4 2493.4 2366.1 2442.2 2442.8 2451.1 2461.3 1755.5 2325.2 2400.2 2408.1 2423.1 2108.7 2430.3 2430.7 2437.1 2441.0 8286.2 8353.3 8366.0 8372.1 8429.0 5214.1 5221.8 5415.3 5477.2 5479.6 3539.0 3595.9 3658.4 3661.1 3667.6 1030.7 1052.5 1077.9 1081.7 1086.1 12171.8 12185.1 12188.8 12191.9 12196.2 4799.6 4863.6 4986.9 5094.6 5095.6 7850.2 9327.0 9405.0 9421.6 9457.9 4727.2 5319.1 5402.2 5402.3 5404.4 4434.4 4484.9 4596.9 4849.5 5020.3 3529.5 3640.5 3831.6 3909.8 3937.5 1212.0 1215.0 1215.5 1224.2 1227.9 803.8 855.5 896.3 915.3 947.6 5759.7 5768.9 6029.8 6104.0 6113.5 449.4 451.3 458.0 983.7 1006.3 3842.4 3897.5 3968.8 3971.0 4000.7 10321.6 10406.7 10850.0 10867.9 10876.9 10625.9 10817.1 10828.4 10835.1 10870.4 8004.4 8087.0 8291.6 8301.5 8378.6 5829.7 5915.9 5966.9 6000.7 6013.3 9972.7 10262.9 10274.3 10284.6 10289.8 9673.0 9910.5 10178.6 10349.2 10357.0 6486.4 6735.1 6935.8 7087.8 7252.2 7028.0 8333.0 8363.7 8466.7 8501.4 7353.5 7426.6 7452.1 7591.4 7596.1 7208.2 7238.0 7280.5 7316.7 7472.9 6936.3 7125.4 7140.9 7143.4 7150.7 4867.3 4946.3 4977.1 5056.4 5112.7 4682.9 4873.5 5033.4 5080.7 5108.5 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4605.24 Median:5001.62 stddev:1460.44 (29.2%) stddev-sans-first:1428.57 02:48:44 INFO - PROCESS | 1663 | Values: 313.1 313.9 319.7 322.0 326.0 4458.0 4560.3 4771.0 5313.8 5367.0 4276.7 5287.0 5288.6 5330.7 5342.3 3856.6 3896.4 3935.4 4095.8 4114.3 3389.0 3998.6 4052.7 4058.5 4064.0 3701.4 4024.3 4041.7 4050.6 4075.4 3725.2 3818.4 3825.2 3954.3 4037.6 3483.4 3495.5 3497.7 3504.0 3542.5 4028.2 4304.1 4851.3 5000.8 5025.1 4104.7 4217.3 4321.7 4612.6 4620.1 5217.4 5459.2 5490.3 5507.6 5537.8 3883.3 3902.9 3903.9 3905.6 3910.2 5575.7 5753.7 5806.0 5843.0 5868.8 3691.9 3895.9 3931.2 3935.4 3967.6 3251.6 3983.4 4020.5 4041.9 4045.2 3848.2 4000.9 4008.6 4503.8 4579.9 4497.3 4891.3 5262.6 5591.3 5648.6 1387.8 1410.8 1433.7 1436.2 1439.8 913.9 938.1 960.4 963.0 965.8 4043.6 4377.4 4945.6 5036.5 5217.0 5768.8 5781.2 5789.3 5807.7 5843.6 4539.6 4793.4 5171.2 5220.7 5243.9 3029.9 3060.4 3169.6 3283.4 3296.6 5536.4 6452.5 6570.9 6592.5 6606.7 5829.4 5887.4 6146.5 6350.6 6395.2 5726.9 5947.1 6045.6 6465.4 6599.2 5972.3 6239.7 6271.4 6297.2 6300.0 4877.9 5708.5 6182.9 6196.3 6205.6 4989.8 5650.8 6390.3 6540.8 6543.5 3667.0 4054.6 4062.2 4069.5 4099.0 5455.9 5576.6 6077.5 6247.6 6265.3 4897.8 5002.4 6035.7 6048.3 6061.9 5443.6 5974.4 5979.2 5981.7 6015.2 5171.2 5650.7 5872.4 5907.6 5930.4 4543.0 4595.7 5027.4 5063.9 5483.5 4876.2 5005.0 5109.8 5280.9 5544.6 02:48:44 INFO - PROCESS | 1663 | 02:48:44 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18332.85 Median:599.93 stddev:25980.39 (4330.6%) stddev-sans-first:26019.28 02:48:44 INFO - PROCESS | 1663 | Values: 635.2 636.2 701.6 721.5 725.5 14258.4 14908.8 15110.5 15435.7 15453.0 14163.9 14224.7 14302.0 14336.7 14343.4 710.8 733.7 884.6 900.8 918.7 924.4 924.9 931.8 931.9 936.9 980.0 984.6 984.6 984.8 985.3 739.9 765.2 767.9 913.5 977.4 706.0 708.0 708.6 709.5 710.9 15346.5 15428.0 15430.2 15435.0 15444.5 12344.3 12430.6 12447.9 12561.4 12745.7 9973.9 10127.3 10316.7 10648.2 10774.1 3041.6 3487.8 3499.5 3576.9 3602.2 73184.3 76985.0 88801.6 88853.0 88885.6 9330.3 9452.5 9580.0 9881.8 10024.1 21211.3 21240.6 21243.5 21255.4 21334.5 7346.3 7510.1 7622.0 7623.9 7685.3 3992.3 4010.7 4035.9 4079.5 4148.6 3537.8 3627.8 3627.8 3628.7 3628.8 520.3 629.3 635.9 641.2 643.0 547.6 555.7 563.5 598.4 598.6 7948.0 8118.0 8130.3 8258.0 8389.9 532.7 546.3 580.3 589.2 601.2 3801.1 3833.5 3851.3 3939.7 4067.7 93371.5 93907.9 94009.6 94679.0 100200.0 77498.0 77699.2 77912.0 78694.0 81877.0 68074.0 69274.7 73666.0 75448.2 76711.6 7367.4 7729.7 8265.8 8297.7 8533.2 42565.0 42811.6 43104.6 44777.6 45387.2 64258.4 64300.7 64347.2 65232.0 65809.0 11139.2 11383.7 11396.0 11457.9 11467.5 11997.3 12351.1 12531.3 12850.7 13184.4 11613.8 11816.2 11859.7 11894.6 11986.5 10842.0 10927.2 11073.9 11388.3 11493.8 10797.9 11285.9 11318.0 11448.3 12379.6 9385.2 9561.2 9787.3 9797.2 9956.4 8289.8 9486.5 9730.6 9810.6 9879.5 02:48:44 INFO - PROCESS | 1663 | -------- Summary: end -------- 02:48:44 INFO - PROCESS | 1663 | 02:48:45 INFO - TEST-INFO | 1663: exit 0 02:48:45 INFO - TEST-OK | dromaeo_css | took 1116879ms 02:48:45 INFO - TEST-START | kraken 02:48:45 INFO - operating with platform_type : mac_ 02:48:45 INFO - Initialising browser for kraken test... 02:48:45 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpazEitG/profile http://localhost:49270/getInfo.html) 02:48:47 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 02:48:47 INFO - PROCESS | 1670 | colorDepth:24 02:48:47 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 02:48:47 INFO - PROCESS | 1670 | __metrics 02:48:47 INFO - PROCESS | 1670 | 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" 02:48:47 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:48:48 INFO - TEST-INFO | 1670: exit 0 02:48:48 INFO - Browser initialized. 02:48:48 INFO - Running cycle 1/1 for kraken test... 02:48:48 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpazEitG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:00 INFO - PROCESS | 1672 | RSS: Main: 239693824 02:49:00 INFO - PROCESS | 1672 | 02:49:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 02:49:03 INFO - PROCESS | 1672 | RSS: Main: 288145408 02:49:03 INFO - PROCESS | 1672 | 02:49:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 02:49:07 INFO - PROCESS | 1672 | RSS: Main: 301154304 02:49:07 INFO - PROCESS | 1672 | 02:49:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 02:49:11 INFO - PROCESS | 1672 | RSS: Main: 388259840 02:49:11 INFO - PROCESS | 1672 | 02:49:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 02:49:14 INFO - PROCESS | 1672 | RSS: Main: 289370112 02:49:14 INFO - PROCESS | 1672 | 02:49:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:16 INFO - PROCESS | 1672 | RSS: Main: 293797888 02:49:16 INFO - PROCESS | 1672 | 02:49:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 02:49:20 INFO - PROCESS | 1672 | RSS: Main: 332623872 02:49:20 INFO - PROCESS | 1672 | 02:49:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 02:49:23 INFO - PROCESS | 1672 | RSS: Main: 332525568 02:49:23 INFO - PROCESS | 1672 | 02:49:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 02:49:27 INFO - PROCESS | 1672 | RSS: Main: 331149312 02:49:27 INFO - PROCESS | 1672 | 02:49:28 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:29 INFO - PROCESS | 1672 | RSS: Main: 322842624 02:49:29 INFO - PROCESS | 1672 | 02:49:31 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:31 INFO - PROCESS | 1672 | RSS: Main: 330604544 02:49:31 INFO - PROCESS | 1672 | 02:49:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:34 INFO - PROCESS | 1672 | RSS: Main: 331300864 02:49:34 INFO - PROCESS | 1672 | 02:49:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:38 INFO - PROCESS | 1672 | RSS: Main: 327770112 02:49:38 INFO - PROCESS | 1672 | 02:49:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:41 INFO - PROCESS | 1672 | RSS: Main: 328527872 02:49:41 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 02:49:43 INFO - PROCESS | 1672 | RSS: Main: 392855552 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | __start_tp_report 02:49:43 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 02:49:43 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 02:49:43 INFO - PROCESS | 1672 | |i|pagename|runs| 02:49:43 INFO - PROCESS | 1672 | |0;ai-astar;111;110;108;111;113;111;112;111;107;112 02:49:43 INFO - PROCESS | 1672 | |1;audio-beat-detection;158;123;146;194;157;161;140;126;191;130 02:49:43 INFO - PROCESS | 1672 | |2;audio-dft;186;186;185;187;183;182;183;181;185;183 02:49:43 INFO - PROCESS | 1672 | |3;audio-fft;72;79;88;89;57;57;47;92;115;81 02:49:43 INFO - PROCESS | 1672 | |4;audio-oscillator;95;104;136;81;80;81;120;79;77;121 02:49:43 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;90;87;89;87;89;86;90;87;89;86 02:49:43 INFO - PROCESS | 1672 | |6;imaging-darkroom;95;95;95;95;97;99;95;112;148;95 02:49:43 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;84;82;87;83;83;84;84;83;84 02:49:43 INFO - PROCESS | 1672 | |8;json-parse-financial;57;60;60;58;58;59;59;79;59;57 02:49:43 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;64;63;67;77;78;72;72;66;66;67 02:49:43 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;78;94;84;82;80;85;82;82;82;82 02:49:43 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;140;140;134;131;151;145;155;141;192;158 02:49:43 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;169;168;170;171;170;171;169;173;167;170 02:49:43 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;70;67;69;71;69;68;69;70;69;70 02:49:43 INFO - PROCESS | 1672 | __end_tp_report 02:49:43 INFO - PROCESS | 1672 | __start_cc_report 02:49:43 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1983 02:49:43 INFO - PROCESS | 1672 | __end_cc_report 02:49:43 INFO - PROCESS | 1672 | __startTimestamp1461664183584__endTimestamp 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | ------- Summary: start ------- 02:49:43 INFO - PROCESS | 1672 | Number of tests: 14 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.84 (1.7%) stddev-sans-first:1.94 02:49:43 INFO - PROCESS | 1672 | Values: 111.0 110.0 108.0 111.0 113.0 111.0 112.0 111.0 107.0 112.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:152.60 Median:157.50 stddev:24.95 (15.8%) stddev-sans-first:26.39 02:49:43 INFO - PROCESS | 1672 | Values: 158.0 123.0 146.0 194.0 157.0 161.0 140.0 126.0 191.0 130.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:1.97 (1.1%) stddev-sans-first:1.96 02:49:43 INFO - PROCESS | 1672 | Values: 186.0 186.0 185.0 187.0 183.0 182.0 183.0 181.0 185.0 183.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:77.70 Median:80.00 stddev:20.20 (25.3%) stddev-sans-first:21.32 02:49:43 INFO - PROCESS | 1672 | Values: 72.0 79.0 88.0 89.0 57.0 57.0 47.0 92.0 115.0 81.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:79.50 stddev:21.61 (27.2%) stddev-sans-first:22.90 02:49:43 INFO - PROCESS | 1672 | Values: 95.0 104.0 136.0 81.0 80.0 81.0 120.0 79.0 77.0 121.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:1.56 (1.8%) stddev-sans-first:1.48 02:49:43 INFO - PROCESS | 1672 | Values: 90.0 87.0 89.0 87.0 89.0 86.0 90.0 87.0 89.0 86.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:95.00 stddev:16.80 (17.7%) stddev-sans-first:17.59 02:49:43 INFO - PROCESS | 1672 | Values: 95.0 95.0 95.0 95.0 97.0 99.0 95.0 112.0 148.0 95.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.32 (1.6%) stddev-sans-first:1.39 02:49:43 INFO - PROCESS | 1672 | Values: 84.0 84.0 82.0 87.0 83.0 83.0 84.0 84.0 83.0 84.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:59.00 stddev:6.55 (11.1%) stddev-sans-first:6.82 02:49:43 INFO - PROCESS | 1672 | Values: 57.0 60.0 60.0 58.0 58.0 59.0 59.0 79.0 59.0 57.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:5.27 (7.6%) stddev-sans-first:5.24 02:49:43 INFO - PROCESS | 1672 | Values: 64.0 63.0 67.0 77.0 78.0 72.0 72.0 66.0 66.0 67.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:82.00 stddev:4.28 (5.2%) stddev-sans-first:4.12 02:49:43 INFO - PROCESS | 1672 | Values: 78.0 94.0 84.0 82.0 80.0 85.0 82.0 82.0 82.0 82.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:148.00 stddev:17.51 (11.8%) stddev-sans-first:18.29 02:49:43 INFO - PROCESS | 1672 | Values: 140.0 140.0 134.0 131.0 151.0 145.0 155.0 141.0 192.0 158.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.00 stddev:1.69 (1.0%) stddev-sans-first:1.76 02:49:43 INFO - PROCESS | 1672 | Values: 169.0 168.0 170.0 171.0 170.0 171.0 169.0 173.0 167.0 170.0 02:49:43 INFO - PROCESS | 1672 | 02:49:43 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:1.14 (1.6%) stddev-sans-first:1.17 02:49:43 INFO - PROCESS | 1672 | Values: 70.0 67.0 69.0 71.0 69.0 68.0 69.0 70.0 69.0 70.0 02:49:43 INFO - PROCESS | 1672 | -------- Summary: end -------- 02:49:43 INFO - PROCESS | 1672 | 02:49:44 INFO - TEST-INFO | 1672: exit 0 02:49:44 INFO - TEST-OK | kraken | took 58811ms 02:49:44 INFO - SUITE-END | took 1175s 02:49:44 INFO - Completed test suite (00:19:36) 02:49:44 INFO - javascript benchmark 02:49:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3384.204711892705, 3413.92461374667, 3442.0706409269496, 3463.6709512596294, 3485.21582643905, 5500.3949565547755, 5618.943810561893, 5680.517156041737, 5699.373068962416, 5703.657780533165, 5579.69311687857, 5630.662160270386, 5631.0708733059, 5635.5773317001185, 5646.971765141168, 4226.577342265779, 5069.0, 5107.0, 5118.07874582575, 5121.8463446096675, 5133.6919784813035, 5147.691138531682, 5162.8193013244545, 5169.250458683488, 5175.689458632475, 5110.182370820669, 5152.845414637567, 5153.922691159635, 5200.557952574017, 5212.687238765668, 5188.999999999981, 5198.896022079575, 5201.323827902386, 5220.321358223445, 5264.31563896691, 4994.225894986246, 5014.598832093424, 5099.59203263738, 5117.974410127963, 5140.280360749498, 5448.99999999996, 5494.0, 5502.80740174096, 5504.972475137639, 5606.35526914406, 6180.969095154541, 6196.318404975449, 6197.969010154966, 6208.286047104596, 6211.0, 5383.703896285702, 5952.315483719384, 6127.433875357673, 6194.318624951296, 6196.690165491753, 5698.82903512887, 5716.399778023331, 5725.627834190765, 5760.36635970043, 5791.855203619901, 6206.596571222856, 6259.593126446857, 6276.623402595859, 6290.087937249074, 6295.087212354183, 6003.729832157476, 6103.725332360054, 6109.572329936951, 6115.694215289218, 6129.724162412702, 5128.8461346159675, 6135.60015773269, 6215.098810672427, 6218.968905155445, 6223.471004964629, 6117.857853872152, 6118.265808102967, 6138.232720909887, 6157.96921015392, 6163.859685958112, 6129.203203583595, 6191.133241346215, 6192.287886892975, 6239.750409983641, 6250.843728906813, 4911.950880491149, 4930.0, 4935.161022626162, 4944.332515110486, 4945.851624451272, 4351.630111440563, 4433.866983990485, 4448.888777780517, 4470.977645111753, 4490.101979604092, 5576.414476479992, 5606.6916319602, 5613.270274864241, 5615.017371959891, 5619.325680918234, 6169.784057557964, 6201.596896201732, 6212.1303017577575, 6226.688665566703, 6237.906431403532, 5562.749676264578, 5618.43815618443, 5632.690202038927, 5651.660900345993, 5670.092785154356, 5325.3875804282225, 5342.5993050521365, 5366.195070739343, 5368.328958880062, 5370.167624018283, 6282.434580887742, 6308.968455157695, 6309.747610095545, 6312.116303717483, 6320.336364681734, 6100.914243738862, 6200.131981522501, 6235.781747638811, 6286.497080233662, 6290.308066112632, 6326.8734625306315, 6331.366863313632, 6346.492280617632, 6347.39699728525, 6354.936450635434, 6126.969365153324, 6146.815595532141, 6150.138980542638, 6160.291566469913, 6174.38256174379, 6102.938970610237, 6139.461473530676, 6183.876322473436, 6228.5951413158, 6281.214848143982, 5680.517156041783, 5721.141828725725, 6091.116788065794, 6091.543134265036, 6123.938760612336, 5875.177475153397, 6018.187544681412, 6047.969760151171, 6070.817875463743, 6076.361981991828, 5692.430756924275, 5786.392428794916, 5799.536037117105, 5888.587798854039, 5894.381089985662, 6007.0, 6017.06735456005, 6054.455099041107, 6076.939230607814, 6078.6048906820915, 5895.056790913433, 5904.114382842608, 5918.082697181943, 5928.762849485972, 5944.613600115978], "value": 5641.254141271318, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.3306985964158, 511.09791218499356, 522.8274669359107, 525.7318767428611, 560.4479587606207, 31186.376272474536, 31933.36133277333, 38511.22977540451, 39539.0, 39559.0, 43871.78064109675, 44626.0, 44633.107337853224, 44696.99999999992, 44726.32910506337, 8999.280057595393, 9788.608455661764, 9798.41209527427, 9805.264605154607, 9809.362391444532, 8396.916030839675, 8605.698800541983, 8621.094785047542, 11176.21766476347, 11475.827862582068, 11557.653270401901, 11805.996490298336, 11822.408879556031, 11828.349440780754, 11851.881481185206, 11657.825132622975, 11853.822192667118, 11856.458660374095, 11857.229280096766, 11860.11049171307, 8397.580120993987, 11128.721781955435, 11392.0, 11398.544058237661, 11399.601013964471, 38313.61686383128, 38840.80579597112, 38896.22207555862, 38903.610963890285, 38926.44294228227, 3130.248740302311, 3365.3437579671972, 3423.1099914022284, 3450.516927630134, 3754.699624030071, 28165.718342816515, 29450.70549294501, 29482.705172948426, 29499.0, 29565.817367305495, 2638.630591717161, 2668.5997100434897, 2807.2280122966145, 2820.2808283887475, 2825.5620378841313, 354340.0, 415394.0, 415761.0, 416775.7483637748, 417475.0, 62501.687491562254, 65302.34697653058, 66130.0080498793, 66254.33745662578, 66264.33735662678, 64305.678471607345, 64427.10078046499, 64428.35571644317, 64433.35566644368, 64561.677191613744, 16797.496075117768, 17155.0, 17156.31374745021, 17157.57106072333, 17165.485035448957, 11592.6522204334, 13077.869221307665, 13163.53927612548, 13184.73630527384, 13190.208587484782, 15767.448139315069, 16458.83541164597, 16863.0, 18917.810821891606, 19416.611667766567, 6814.897776533356, 7730.57481838493, 7806.843863122819, 8033.678652853937, 8101.096242383035, 912.2473958983865, 954.5704433005176, 960.4669408478362, 992.6575331510618, 1118.4072441605963, 29390.11829645114, 29500.704992950225, 29645.407091858044, 29656.851715741715, 29782.851085744867, 1046.1160319529986, 1056.3239526702916, 1099.3623698254994, 1131.9943400282946, 1167.3754541320523, 15428.460003899809, 15502.9224853875, 15523.767143492856, 15662.981906176063, 15756.842431575538, 643450.5654943391, 654245.0, 661076.0, 661203.0, 662880.0, 60469.395306046375, 61118.694406527684, 61157.08264376038, 61159.08261376083, 61160.08259876105, 1310.1876836361516, 1440.4598275646424, 1480.726065677853, 1486.1900264355736, 1492.783546385768, 28971.7102828969, 29109.41781164407, 29124.417511650074, 29832.10503684893, 29843.10470685883, 47627.76186119047, 48343.5165648339, 48576.0, 48653.27020094772, 48770.0, 383745.0, 384564.0, 384768.07615962863, 385016.0, 385547.0, 22583.88708056449, 25571.0, 26125.346866327734, 26374.340641484116, 26437.810298536995, 42878.78560607177, 43918.0, 44278.55721442744, 44632.77683611561, 44859.55140448684, 35812.0, 36059.81970090238, 36697.81651091728, 36902.26195476129, 37047.81476092602, 35204.76783312572, 37023.0, 37132.44301335536, 38134.23731525298, 38199.236015278984, 36641.63358366489, 37028.81485592555, 37229.0, 38298.42552361773, 38307.425388618634, 29087.12738617845, 31008.53487197739, 32683.836580816944, 33182.5022624656, 33744.15639609029, 24598.049880737562, 24693.259202223962, 24730.752692473565, 25261.368465788502, 25414.872925635253], "value": 19769.090220102906, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.69436825882127, 374.90153849221775, 394.01692776522583, 397.60636969400304, 406.32549967054695, 19186.0, 20664.276750313744, 20694.586108277843, 20768.06543705533, 21983.780162198374, 21016.684749728767, 21087.367378978655, 22085.116595336167, 22120.446988825286, 22214.777852221472, 5285.762140703678, 5841.3282472515675, 6177.227846519185, 6185.319614842364, 6192.78325258616, 3985.4818873546396, 4230.0, 5865.912011319833, 6142.570020098595, 6192.318844927055, 6153.476954458876, 6182.8454288642915, 6186.7525298987985, 6186.81439556814, 6191.040388739752, 6096.2074930259005, 6204.689765511729, 6207.0, 6210.968945155268, 6222.0355844844125, 3878.1855810279876, 4956.975215123937, 5802.520357308901, 6018.3981601839905, 6037.969810150965, 12897.548585799452, 13373.130746501447, 13445.529406470727, 13581.796273055912, 13592.11651242666, 1957.7552805899263, 2016.6269240190466, 2153.7630860605323, 2740.3937015706238, 2775.8056936014486, 9360.906390936072, 9896.604135834557, 9951.70144895647, 9957.153641940444, 9961.203103751683, 1656.2546853915785, 1689.3158270900306, 1695.6015336395938, 1710.0509217384347, 1712.1781544858538, 93420.53289733508, 124151.0, 128364.0, 132492.0, 133275.6672433273, 11535.711607209887, 11765.705857353463, 11770.52917883275, 11797.52809887612, 11876.703082423008, 15182.0, 15277.541673749805, 15291.770623440658, 15304.38782448712, 15305.933698950721, 8618.612162452704, 8680.131986801269, 8695.56631763943, 8775.0, 8884.289256859436, 8671.4363566368, 8684.956575217084, 8688.522131282714, 8770.473771573726, 8796.020895298634, 3945.704072194597, 4012.996750812297, 4531.478879928851, 4694.319323698045, 4729.716217026989, 1542.537238828347, 1812.238859678938, 1828.8479943666657, 1941.5728539721522, 2008.9095990680455, 850.6342272822745, 913.5885057586046, 917.499962520428, 987.3878195518824, 1069.6363236499628, 20384.0, 20543.76737395731, 20587.79412205859, 20735.0, 20844.895775521025, 935.934484586086, 949.4161090929167, 970.5875003123645, 971.7949742319473, 989.1839232633107, 9410.247180225566, 10934.56261749537, 10938.890611093946, 11292.943535282435, 11319.207655464039, 613042.0, 620485.0, 624555.0, 631605.0, 633984.0, 128082.0, 134648.9628048989, 136010.71084223044, 137781.0, 138025.309873452, 931.8509038553799, 940.5109343141528, 985.1626117799813, 988.3328753091635, 1001.9899801001897, 16900.746488802924, 18553.25786968506, 18911.621767564295, 19078.236870525023, 19517.12172952249, 52441.0, 54750.17874731962, 55544.72227638836, 55846.44153558413, 56635.58411039757, 73344.26655733588, 74116.25883741309, 81464.0, 81748.59125704333, 84183.0, 11476.368799716096, 11755.294682319089, 12104.213226140273, 12178.939105304416, 12309.692257693629, 12363.93818030904, 13371.799423008468, 13394.330283485982, 13422.261775602436, 13779.448822047407, 11405.0, 11440.771184576428, 11682.182247242612, 11780.175387722777, 11847.526098955945, 9456.669016584387, 11428.82856757166, 11445.313281203154, 11561.479733411848, 11750.58872939443, 11447.25592836463, 11614.12894032934, 11824.054075674097, 11870.881291186977, 12143.39283035827, 11944.64166075019, 12018.879811201776, 12194.51221951112, 12254.571090011807, 12262.20295680778, 10670.73323166927, 11634.76730465403, 11848.407579621156, 11941.880581194077, 11960.102992275788], "value": 9715.923553731274, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.4267521653733, 1588.5631451350878, 1594.25070216998, 1841.5304097455146, 1968.075004747768, 7800.258975397335, 8597.26923211527, 8801.91198088019, 8819.0, 8838.823223535534, 8435.789105272372, 8660.047394786568, 8670.0, 8682.696105636303, 8690.130986901308, 2124.893755312236, 2365.6215005599106, 2408.50625621748, 2439.9268021959283, 2460.5694003549397, 2457.803375729937, 2481.813863960201, 2487.987560062197, 2489.352768280251, 2493.4265119022634, 2366.1363602285214, 2442.2306973303444, 2442.767937045987, 2451.1053465485147, 2461.310832966772, 1755.49090763679, 2325.2210509479346, 2400.1839374612714, 2408.1330720940446, 2423.09134074722, 2108.652072408051, 2430.2587710748217, 2430.7447717989535, 2437.110454684045, 2440.9511809763885, 8286.212809783061, 8353.33173346131, 8365.95817020911, 8372.120927302667, 8429.0, 5214.139666954948, 5221.765020574083, 5415.268938693265, 5477.20580515823, 5479.643823151482, 3538.982305088458, 3595.94606080909, 3658.432942893829, 3661.0847288177956, 3667.6149004354693, 1030.7165529479378, 1052.4737631184407, 1077.8760442549128, 1081.707938856512, 1086.1093902999544, 12171.756564868741, 12185.086118541145, 12188.756224875542, 12191.939040304831, 12196.207246528948, 4799.568038876448, 4863.562279394871, 4986.8753281168265, 5094.580074464674, 5095.566876815512, 7850.214978502218, 9327.0, 9405.0, 9421.576029078708, 9457.90542094584, 4727.172744769651, 5319.1223448131395, 5402.189671549299, 5402.297701298885, 5404.405515393303, 4434.357018232338, 4484.865454036384, 4596.931046034312, 4849.466558678542, 5020.322256495399, 3529.5235143255845, 3640.5449318835144, 3831.5593706723525, 3909.7654140751647, 3937.547182074041, 1211.9939400302942, 1214.9878501214876, 1215.5198696514826, 1224.185916365607, 1227.9324637144864, 803.843250566134, 855.5422848776, 896.3098414221014, 915.3226612306845, 947.6091928983739, 5759.6832174229985, 5768.855778605484, 6029.849253768691, 6104.023356262976, 6113.480354169857, 449.38658730832833, 451.345548954018, 457.95878370946775, 983.7294743945472, 1006.2603986070233, 3842.3852183650483, 3897.538423091341, 3968.8214030368704, 3970.960290397059, 4000.7199496035, 10321.587136514605, 10406.739831504121, 10850.023497885228, 10867.945660271806, 10876.891231087588, 10625.9468702656, 10817.080548153337, 10828.350298982086, 10835.079018283377, 10870.402127882884, 8004.359651227888, 8087.029556453145, 8291.626876790559, 8301.46040507365, 8378.58107094655, 5829.708514574169, 5915.947575910515, 5966.880662386815, 6000.669963152069, 6013.278406591062, 9972.700818975442, 10262.897371026194, 10274.33216840903, 10284.588616455258, 10289.794204116026, 9672.984336644551, 9910.454924979196, 10178.592856285963, 10349.223808214263, 10357.0, 6486.3513648636645, 6735.090762746966, 6935.791926242221, 7087.787366379017, 7252.238514956065, 7027.964860175666, 8332.958335208285, 8363.707270245512, 8466.74599762008, 8501.447405918596, 7353.485256032026, 7426.628668566658, 7452.143003554444, 7591.3547348475995, 7596.08846938371, 7208.243134471015, 7238.022866912899, 7280.453965952681, 7316.707331706885, 7472.850542989001, 6936.271691472322, 7125.429965602843, 7140.9285907140265, 7143.428525718034, 7150.713971441084, 4867.31172407605, 4946.258061290835, 4977.129002424489, 5056.367954005749, 5112.71880046601, 4682.859514214579, 4873.51264873524, 5033.421156567042, 5080.745962701776, 5108.540231379194], "value": 4538.207729910982, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.1357453428537, 313.9497680371141, 319.6563694028919, 322.0467416447316, 326.0284352629167, 4457.9554204457945, 4560.270356742922, 4770.9761451192735, 5313.7947751683005, 5367.0, 4276.700630955835, 5287.048331300364, 5288.629795914288, 5330.733463326837, 5342.278792363029, 3856.5757766645647, 3896.4350169225445, 3935.3703407454814, 4095.75425474471, 4114.341705327149, 3388.9833050085, 3998.6001399860074, 4052.696047796412, 4058.492688413948, 4063.9796801015955, 3701.4262789267705, 4024.3359845625437, 4041.6766658667234, 4050.5949405059555, 4075.4498142750795, 3725.2363265530503, 3818.369968955119, 3825.190961171247, 3954.3079961006997, 4037.6164264394833, 3483.3729928613093, 3495.5455790747296, 3497.720182385403, 3504.0, 3542.5394698689006, 4028.174224284044, 4304.053108316165, 4851.345068415754, 5000.824971125993, 5025.0954828130925, 4104.665983555356, 4217.304144816102, 4321.675874309408, 4612.58486736192, 4620.145273124483, 5217.399999000126, 5459.181122831567, 5490.258815059956, 5507.614466987313, 5537.833864984057, 3883.339832228528, 3902.8829135125707, 3903.9219215615813, 3905.648491635738, 3910.1593157470998, 5575.721213939287, 5753.741081651294, 5805.993864233707, 5842.970785146047, 5868.794592189253, 3691.8707845225827, 3895.941560876589, 3931.233409485123, 3935.35066713989, 3967.6032396760093, 3251.5935508061493, 3983.42240375144, 4020.4974378202724, 4041.939370909439, 4045.211183819186, 3848.1913500654646, 4000.9199816003515, 4008.599140085968, 4503.797329120198, 4579.862604121881, 4497.3254011897825, 4891.315215869781, 5262.578993680497, 5591.30108736408, 5648.63283886546, 1387.7779555271109, 1410.8307003159687, 1433.7419264532275, 1436.2029073863896, 1439.820022497188, 913.8622415093118, 938.1275413865035, 960.3901522533241, 962.9807403851884, 965.7633879699519, 4043.553650041741, 4377.387165796791, 4945.604351651859, 5036.471170527042, 5217.043608646757, 5768.846230753782, 5781.184418283912, 5789.3052833659385, 5807.722301093752, 5843.561732870052, 4539.591436770706, 4793.448753393334, 5171.172612382001, 5220.738963051832, 5243.879891464572, 3029.8939537116094, 3060.403221371811, 3169.6354919184128, 3283.3925723741186, 3296.6373698893103, 5536.4186760390385, 6452.516061295421, 6570.868582628321, 6592.538522303488, 6606.7026983785845, 5829.446202610745, 5887.381824908409, 6146.496496996759, 6350.571121497718, 6395.232572091379, 5726.856828579319, 5947.1079338099635, 6045.579288867113, 6465.418112369909, 6599.241087274832, 5972.343042265435, 6239.688015599111, 6271.40079279799, 6297.1813664223355, 6300.0, 4877.926831097466, 5708.457696518824, 6182.938170618416, 6196.287426945959, 6205.596636218631, 4989.7525618595355, 5650.78508120756, 6390.265119511129, 6540.771073012422, 6543.509236807164, 3667.028237517007, 4054.574269701639, 4062.1923244157247, 4069.491313585802, 4099.0, 5455.918161227664, 5576.609637325349, 6077.513798896166, 6247.562670613195, 6265.310815810349, 4897.804087836589, 5002.424721157114, 6035.69821508914, 6048.274207095177, 6061.878762424815, 5443.564514838724, 5974.372690867396, 5979.162917191684, 5981.671008094422, 6015.218021657157, 5171.198464238049, 5650.717464126859, 5872.354041055395, 5907.556933229939, 5930.436608522184, 4542.977285113553, 4595.701279416827, 5027.441493137139, 5063.87340316495, 5483.4516548344845, 4876.244182151772, 5004.999000200048, 5109.795608175631, 5280.947190528045, 5544.584156188222], "value": 4157.096154349205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.2123367024889, 636.1539152926607, 701.5755467941894, 721.5310048468498, 725.5139056831925, 14258.358373873201, 14908.776368354469, 15110.471133510302, 15435.69128617427, 15453.0, 14163.858361416384, 14224.71550568988, 14301.998860079773, 14336.71326573468, 14343.426262949495, 710.77610552676, 733.7395224695236, 884.5709830732089, 900.7658008917663, 918.6646873891049, 924.3714274293478, 924.9213816825578, 931.7717159295981, 931.9161275485205, 936.929730270229, 979.9608015679399, 984.6455276100598, 984.6455276100598, 984.8375018122001, 985.2512090810964, 739.8557281330143, 765.2041876448543, 767.8656235158825, 913.4610579757974, 977.4037836919473, 705.9647017649148, 708.0087876972281, 708.6456771614193, 709.4572651921285, 710.8507213485175, 15346.539603811903, 15428.0, 15430.228488575638, 15434.996725212937, 15444.5366639001, 12344.259344439362, 12430.627081187578, 12447.875521244852, 12561.371931403393, 12745.681357966032, 9973.900260997443, 10127.341722787995, 10316.690499284958, 10648.201384896087, 10774.138068954464, 3041.635003799536, 3487.7732947358595, 3499.4575840744724, 3576.928461430783, 3602.2075143468396, 73184.26815731882, 76984.99999999945, 88801.55599221963, 88853.0, 88885.55557222173, 9330.312335410328, 9452.480113593818, 9580.041995800504, 9881.80236395268, 10024.097831195228, 21211.257605983716, 21240.575188496143, 21243.46891327698, 21255.36233912985, 21334.466638334165, 7346.33882950537, 7510.061242344707, 7621.961890190513, 7623.885641715378, 7685.269899359552, 3992.3412636915164, 4010.7393019453643, 4035.9192816143513, 4079.5104587449696, 4148.647364973951, 3537.7700449471217, 3627.782333060025, 3627.818609069536, 3628.691561217326, 3628.8004159771467, 520.3287758791192, 629.2511910826103, 635.9236891573041, 641.1696852575925, 643.0, 547.625460095164, 555.7304707216997, 563.5463451921242, 598.4404581716109, 598.6348327520209, 7948.0, 8118.0, 8130.308923741548, 8258.0, 8389.874151887727, 532.6750682083925, 546.3498436860124, 580.274656679151, 589.1604463639297, 601.227422761749, 3801.125741079512, 3833.5208098987628, 3851.268259030775, 3939.7045221608496, 4067.7355971861734, 93371.53314233385, 93907.91355474293, 94009.58985615222, 94679.0, 100200.0, 77498.0, 77699.22300777033, 77912.0, 78694.0, 81877.0, 68074.0, 69274.65362673154, 73666.0, 75448.24551754413, 76711.61644191743, 7367.373773229336, 7729.652165652672, 8265.83468330618, 8297.668093276201, 8533.232009119209, 42565.0, 42811.571884282006, 43104.56895431005, 44777.55222447734, 45387.234340393785, 64258.357416427105, 64300.678496607215, 64347.24353942768, 65232.0, 65809.01286480803, 11139.220254582102, 11383.658490245305, 11396.0, 11457.885421145682, 11467.541298347973, 11997.340146291688, 12351.135420520477, 12531.310777907303, 12850.67873303175, 13184.406701698255, 11613.825792612948, 11816.231944923553, 11859.703507412383, 11894.583689570823, 11986.46060927243, 10842.024217820433, 10927.235093543375, 11073.892213188106, 11388.316700997966, 11493.770124597295, 10797.946010269898, 11285.887141128484, 11318.037966772916, 11448.313101213955, 12379.62861114168, 9385.249180065715, 9561.21546464379, 9787.314887957775, 9797.21622270231, 9956.402615843073, 8289.751307460785, 9486.525673716149, 9730.610775568897, 9810.607575697177, 9879.456629885426], "value": 6870.316291104303, "unit": "score"}], "name": "dromaeo_css", "value": 7209.810810797515, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 108.0, 111.0, 113.0, 111.0, 112.0, 111.0, 107.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 146.0, 194.0, 157.0, 161.0, 140.0, 126.0, 191.0, 130.0], "value": 152.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 185.0, 187.0, 183.0, 182.0, 183.0, 181.0, 185.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 88.0, 89.0, 57.0, 57.0, 47.0, 92.0, 115.0, 81.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 104.0, 136.0, 81.0, 80.0, 81.0, 120.0, 79.0, 77.0, 121.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 97.0, 99.0, 95.0, 112.0, 148.0, 95.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 82.0, 87.0, 83.0, 83.0, 84.0, 84.0, 83.0, 84.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 60.0, 58.0, 58.0, 59.0, 59.0, 79.0, 59.0, 57.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 67.0, 77.0, 78.0, 72.0, 72.0, 66.0, 66.0, 67.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 94.0, 84.0, 82.0, 80.0, 85.0, 82.0, 82.0, 82.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 134.0, 131.0, 151.0, 145.0, 155.0, 141.0, 192.0, 158.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 170.0, 171.0, 170.0, 171.0, 169.0, 173.0, 167.0, 170.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 69.0, 71.0, 69.0, 68.0, 69.0, 70.0, 69.0, 70.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1497.3999999999999, "alertThreshold": 2.0}]} 02:49:44 INFO - Return code: 0 02:49:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:49:44 ERROR - # TBPL SUCCESS # 02:49:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:44 INFO - 2016-04-26 02:49:44.898 screenresolution[1676:9558] starting screenresolution argv=screenresolution get 02:49:44 INFO - 2016-04-26 02:49:44.900 screenresolution[1676:9558] Display 0: 1600x1200x32@50 02:49:44 INFO - 2016-04-26 02:49:44.906 screenresolution[1677:9561] starting screenresolution argv=screenresolution list 02:49:44 INFO - Available Modes on Display 0 02:49:44 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 02:49:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:44 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 02:49:44 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 02:49:45 INFO - Graphics/Displays: 02:49:45 INFO - Intel Iris: 02:49:45 INFO - Chipset Model: Intel Iris 02:49:45 INFO - Type: GPU 02:49:45 INFO - Bus: Built-In 02:49:45 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:45 INFO - Vendor: Intel (0x8086) 02:49:45 INFO - Device ID: 0x0a2e 02:49:45 INFO - Revision ID: 0x0009 02:49:45 INFO - Displays: 02:49:45 INFO - Display: 02:49:45 INFO - Resolution: 1600 x 1200 @ 50 Hz 02:49:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:45 INFO - Display Serial Number: 1600x1200 50 02:49:45 INFO - Main Display: Yes 02:49:45 INFO - Mirror: Off 02:49:45 INFO - Online: Yes 02:49:45 INFO - Rotation: Supported 02:49:45 INFO - Return code: 0 02:49:45 INFO - Running post-action listener: _resource_record_post_action 02:49:45 INFO - Running post-run listener: _resource_record_post_run 02:49:46 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38151680; Write bytes: 694886912; Read time: 397; Write time: 2012 02:49:46 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 388979200; Write bytes: 383555584; Read time: 29676; Write time: 736 02:49:46 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36300288; Write bytes: 295680512; Read time: 375; Write time: 1219 02:49:46 INFO - Running post-run listener: _upload_blobber_files 02:49:46 INFO - Blob upload gear active. 02:49:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:46 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 02:49:46 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:49:46 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:49:46 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:49:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:49:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:47 INFO - (blobuploader) - INFO - Done attempting. 02:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:48 INFO - (blobuploader) - INFO - Done attempting. 02:49:48 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:48 INFO - Return code: 0 02:49:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/44a2510696adef7e5ee5a4d87801bb8984c32c10c7fd3e1397212e38e80659886358af85779da51b2c5e439c4a0eb2b82cfd418c34b97e46eeec6641c5d1536e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0af721b776ab95c1eed12bf9776fa93b47c49bb89583349d5cd25dedb92f9ce26c539feafcaa288458dddf403fc916cd05211d05e3857202eb6a7c20af571fd7"} 02:49:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:48 INFO - Contents: 02:49:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/44a2510696adef7e5ee5a4d87801bb8984c32c10c7fd3e1397212e38e80659886358af85779da51b2c5e439c4a0eb2b82cfd418c34b97e46eeec6641c5d1536e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0af721b776ab95c1eed12bf9776fa93b47c49bb89583349d5cd25dedb92f9ce26c539feafcaa288458dddf403fc916cd05211d05e3857202eb6a7c20af571fd7"} 02:49:48 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:48 INFO - Copying logs to upload dir... 02:49:48 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.132686 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-26 02:49:48.951895) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 02:49:48.959548) ========= 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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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/Fx-Team/sha512/44a2510696adef7e5ee5a4d87801bb8984c32c10c7fd3e1397212e38e80659886358af85779da51b2c5e439c4a0eb2b82cfd418c34b97e46eeec6641c5d1536e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0af721b776ab95c1eed12bf9776fa93b47c49bb89583349d5cd25dedb92f9ce26c539feafcaa288458dddf403fc916cd05211d05e3857202eb6a7c20af571fd7"} build_url:https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011000 build_url: 'https://queue.taskcluster.net/v1/task/Yma-DbgYTGumv85K3Szulg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/44a2510696adef7e5ee5a4d87801bb8984c32c10c7fd3e1397212e38e80659886358af85779da51b2c5e439c4a0eb2b82cfd418c34b97e46eeec6641c5d1536e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0af721b776ab95c1eed12bf9776fa93b47c49bb89583349d5cd25dedb92f9ce26c539feafcaa288458dddf403fc916cd05211d05e3857202eb6a7c20af571fd7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 02:49:48.992858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:49:48.993271) ========= 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.Knjc4ApWB6/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.YEW6AzQDoj/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:49:49.041981) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 02:49:49.045379) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 02:49:49.045870) ========= ========= Total master_lag: 0.18 =========