builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0026 starttime: 1461269489.99 results: success (0) buildid: 20160421110016 builduid: 7bb95e9d9f4049b387b9a0d6fec08b2c revision: fed6d478bf3d7cc80fbcf975361a2e2bc8e4ed30 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 13:11:29.988047) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 13:11:29.988516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-04-21 13:11:29.988813) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-04-21 13:11:37.842129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-21 13:11:37.842400) ========= 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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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-21 13:11:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.1M=0.001s 2016-04-21 13:11:38 (10.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.408559 ========= master_lag: 3.83 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-21 13:11:42.076278) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:11:42.076556) ========= 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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.026447 ========= master_lag: 0.17 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:11:42.272405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-21 13:11:42.272730) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fed6d478bf3d7cc80fbcf975361a2e2bc8e4ed30 --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 fed6d478bf3d7cc80fbcf975361a2e2bc8e4ed30 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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-21 13:11:42,351 truncating revision to first 12 chars 2016-04-21 13:11:42,351 Setting DEBUG logging. 2016-04-21 13:11:42,351 attempt 1/10 2016-04-21 13:11:42,351 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fed6d478bf3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 13:11:43,445 unpacking tar archive at: fx-team-fed6d478bf3d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.416140 ========= master_lag: 1.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-21 13:11:44.937901) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 13:11:44.938182) ========= script_repo_revision: fed6d478bf3d7cc80fbcf975361a2e2bc8e4ed30 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 13:11:44.938574) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-21 13:11:44.941914) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-21 13:11:49.028197) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-21 13:11:49.028510) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:11:49 INFO - MultiFileLogger online at 20160421 13:11:49 in /builds/slave/test 13:11:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:11:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:11:49 INFO - {'append_to_log': False, 13:11:49 INFO - 'base_work_dir': '/builds/slave/test', 13:11:49 INFO - 'blob_upload_branch': 'Fx-Team', 13:11:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:11:49 INFO - 'branch': 'Fx-Team', 13:11:49 INFO - 'buildbot_json_path': 'buildprops.json', 13:11:49 INFO - 'config_files': ('talos/mac_config.py',), 13:11:49 INFO - 'default_actions': ('clobber', 13:11:49 INFO - 'read-buildbot-config', 13:11:49 INFO - 'download-and-extract', 13:11:49 INFO - 'populate-webroot', 13:11:49 INFO - 'create-virtualenv', 13:11:49 INFO - 'install', 13:11:49 INFO - 'run-tests'), 13:11:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:11:49 INFO - 'download_minidump_stackwalk': True, 13:11:49 INFO - 'download_symbols': 'ondemand', 13:11:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:11:49 INFO - 'tooltool.py': '/tools/tooltool.py', 13:11:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:11:49 INFO - '/tools/misc-python/virtualenv.py')}, 13:11:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:11:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:11:49 INFO - 'installer_path': 'installer.exe', 13:11:49 INFO - 'log_level': 'info', 13:11:49 INFO - 'log_name': 'talos', 13:11:49 INFO - 'log_to_console': True, 13:11:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:11:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:11:49 INFO - 'opt_config_files': (), 13:11:49 INFO - 'pip_index': False, 13:11:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:11:49 INFO - 'cmd': ('bash', 13:11:49 INFO - '-c', 13:11:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:11:49 INFO - 'enabled': True, 13:11:49 INFO - 'halt_on_failure': False, 13:11:49 INFO - 'name': 'check_screen_resolution'},), 13:11:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:11:49 INFO - 'cmd': ('bash', 13:11:49 INFO - '-c', 13:11:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:11:49 INFO - 'enabled': True, 13:11:49 INFO - 'halt_on_failure': False, 13:11:49 INFO - 'name': 'check_screen_resolution'},), 13:11:49 INFO - 'run_cmd_checks_enabled': True, 13:11:49 INFO - 'sps_profile': False, 13:11:49 INFO - 'sps_profile_interval': 0, 13:11:49 INFO - 'suite': 'dromaeojs-e10s', 13:11:49 INFO - 'system_bits': '32', 13:11:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:11:49 INFO - 'title': 't-yosemite-r7-0026', 13:11:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:11:49 INFO - 'use_talos_json': True, 13:11:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:11:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:11:49 INFO - 'work_dir': 'build'} 13:11:49 INFO - ##### 13:11:49 INFO - ##### Running clobber step. 13:11:49 INFO - ##### 13:11:49 INFO - Running pre-action listener: _resource_record_pre_action 13:11:49 INFO - Running main action method: clobber 13:11:49 INFO - rmtree: /builds/slave/test/build 13:11:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:11:51 INFO - Running post-action listener: _resource_record_post_action 13:11:51 INFO - ##### 13:11:51 INFO - ##### Running read-buildbot-config step. 13:11:51 INFO - ##### 13:11:51 INFO - Running pre-action listener: _resource_record_pre_action 13:11:51 INFO - Running main action method: read_buildbot_config 13:11:51 INFO - Using buildbot properties: 13:11:51 INFO - { 13:11:51 INFO - "project": "", 13:11:51 INFO - "product": "firefox", 13:11:51 INFO - "script_repo_revision": "production", 13:11:51 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 13:11:51 INFO - "repository": "", 13:11:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 13:11:51 INFO - "buildid": "20160421110016", 13:11:51 INFO - "pgo_build": "False", 13:11:51 INFO - "basedir": "/builds/slave/test", 13:11:51 INFO - "buildnumber": 618, 13:11:51 INFO - "slavename": "t-yosemite-r7-0026", 13:11:51 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:11:51 INFO - "platform": "yosemite_r7", 13:11:51 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 13:11:51 INFO - "branch": "fx-team", 13:11:51 INFO - "repo_path": "integration/fx-team", 13:11:51 INFO - "revision": "fed6d478bf3d7cc80fbcf975361a2e2bc8e4ed30", 13:11:51 INFO - "stage_platform": "macosx64", 13:11:51 INFO - "builduid": "7bb95e9d9f4049b387b9a0d6fec08b2c", 13:11:51 INFO - "slavebuilddir": "test" 13:11:51 INFO - } 13:11:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 13:11:51 INFO - Running post-action listener: _resource_record_post_action 13:11:51 INFO - ##### 13:11:51 INFO - ##### Running download-and-extract step. 13:11:51 INFO - ##### 13:11:51 INFO - Running pre-action listener: _resource_record_pre_action 13:11:51 INFO - Running main action method: download_and_extract 13:11:51 INFO - mkdir: /builds/slave/test/build/tests 13:11: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')]} 13:11:51 INFO - https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:11:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:11:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:11:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 13:11:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 13:11:52 INFO - Downloaded 1199 bytes. 13:11:52 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 13:11:52 INFO - Using the following test package requirements: 13:11:52 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:11:52 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 13:11:52 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 13:11:52 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'jsshell-mac.zip'], 13:11:52 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 13:11:52 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:11:52 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 13:11:52 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 13:11:52 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 13:11:52 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:11:52 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 13:11:52 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:11: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')]} 13:11:52 INFO - https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:11:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:11:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:11:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:11:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:11:54 INFO - Downloaded 18099919 bytes. 13:11:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:11:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:11:57 INFO - Return code: 0 13:11:57 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:57 INFO - https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:11:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:11:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:11:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:11:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:11:58 INFO - Downloaded 18099919 bytes. 13:11:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:11:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:12:01 INFO - Return code: 0 13:12: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')]} 13:12:01 INFO - https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:12:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:12:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:12:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:12:03 INFO - Downloaded 10845952 bytes. 13:12:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:12:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:12:04 INFO - Return code: 0 13:12:04 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')]} 13:12:04 INFO - https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:12:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:12:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:12:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 13:12:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 13:12:07 INFO - Downloaded 109955362 bytes. 13:12:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:12:07 INFO - mkdir: /builds/slave/test/properties 13:12:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:12:07 INFO - Writing to file /builds/slave/test/properties/build_url 13:12:07 INFO - Contents: 13:12:07 INFO - build_url:https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:12:07 INFO - Running post-action listener: _resource_record_post_action 13:12:07 INFO - Running post-action listener: set_extra_try_arguments 13:12:07 INFO - ##### 13:12:07 INFO - ##### Running populate-webroot step. 13:12:07 INFO - ##### 13:12:07 INFO - Running pre-action listener: _resource_record_pre_action 13:12:07 INFO - Running main action method: populate_webroot 13:12:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:12:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:12:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:12:07 INFO - '--tppagecycles', 13:12:07 INFO - '7'], 13:12:07 INFO - 'tests': ['tsvgm']}}, 13:12:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:12:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:12:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:12:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:12:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['damp', 'tps']}, 13:12:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'tests': ['damp', 'tps']}, 13:12:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['dromaeo_dom']}, 13:12:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:12:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['a11yr', 13:12:07 INFO - 'ts_paint', 13:12:07 INFO - 'tpaint', 13:12:07 INFO - 'sessionrestore', 13:12:07 INFO - 'sessionrestore_no_auto_restore', 13:12:07 INFO - 'tabpaint']}, 13:12:07 INFO - 'other-e10s': {'tests': ['a11yr', 13:12:07 INFO - 'ts_paint', 13:12:07 INFO - 'tpaint', 13:12:07 INFO - 'sessionrestore', 13:12:07 INFO - 'sessionrestore_no_auto_restore', 13:12:07 INFO - 'tabpaint']}, 13:12:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:12:07 INFO - 'ts_paint', 13:12:07 INFO - 'tpaint', 13:12:07 INFO - 'sessionrestore', 13:12:07 INFO - 'sessionrestore_no_auto_restore', 13:12:07 INFO - 'tabpaint']}, 13:12:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:12:07 INFO - 'ts_paint', 13:12:07 INFO - 'tpaint', 13:12:07 INFO - 'sessionrestore', 13:12:07 INFO - 'sessionrestore_no_auto_restore', 13:12:07 INFO - 'tabpaint']}, 13:12:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['a11yr', 13:12:07 INFO - 'ts_paint', 13:12:07 INFO - 'tpaint', 13:12:07 INFO - 'sessionrestore', 13:12:07 INFO - 'sessionrestore_no_auto_restore', 13:12:07 INFO - 'tabpaint']}, 13:12:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['a11yr', 13:12:07 INFO - 'ts_paint', 13:12:07 INFO - 'tpaint', 13:12:07 INFO - 'sessionrestore', 13:12:07 INFO - 'sessionrestore_no_auto_restore', 13:12:07 INFO - 'tabpaint']}, 13:12:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['tsvgx', 13:12:07 INFO - 'tsvgr_opacity', 13:12:07 INFO - 'tart', 13:12:07 INFO - 'tscrollx', 13:12:07 INFO - 'cart']}, 13:12:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:12:07 INFO - 'tsvgr_opacity', 13:12:07 INFO - 'tart', 13:12:07 INFO - 'tscrollx', 13:12:07 INFO - 'cart']}, 13:12:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'talos_options': ['--disable-e10s'], 13:12:07 INFO - 'tests': ['tp5o']}, 13:12:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'tests': ['tp5o']}, 13:12:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'talos_options': ['--disable-e10s', 13:12:07 INFO - '--xperf_path', 13:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:07 INFO - 'tests': ['tp5n']}, 13:12:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:07 INFO - 'talos_options': ['--xperf_path', 13:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:07 INFO - 'tests': ['tp5n']}}, 13:12:07 INFO - 'talos.zip': {'path': '', 13:12:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:12:07 INFO - Running post-action listener: _resource_record_post_action 13:12:07 INFO - ##### 13:12:07 INFO - ##### Running create-virtualenv step. 13:12:07 INFO - ##### 13:12:07 INFO - Running pre-action listener: _resource_record_pre_action 13:12:07 INFO - Running main action method: create_virtualenv 13:12:07 INFO - Creating virtualenv /builds/slave/test/build/venv 13:12:07 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 13:12:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:12:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:12:07 INFO - Using real prefix '/tools/python27' 13:12:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:12:08 INFO - Installing distribute.............................................................................................................................................................................................done. 13:12:11 INFO - Installing pip.................done. 13:12:11 INFO - Return code: 0 13:12:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:11 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:11 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 13:12:11 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 13:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:11 INFO - 'HOME': '/Users/cltbld', 13:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:11 INFO - 'LOGNAME': 'cltbld', 13:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:11 INFO - 'MOZ_NO_REMOTE': '1', 13:12:11 INFO - 'NO_EM_RESTART': '1', 13:12:11 INFO - 'PAGER': '/bin/cat', 13:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:11 INFO - 'PWD': '/builds/slave/test', 13:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:11 INFO - 'SHELL': '/bin/bash', 13:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:11 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:11 INFO - 'USER': 'cltbld', 13:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:11 INFO - 'XPC_FLAGS': '0x0', 13:12:11 INFO - 'XPC_SERVICE_NAME': '0', 13:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:11 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 13:12:11 INFO - Cleaning up... 13:12:11 INFO - Return code: 0 13:12:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:11 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:11 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 13:12:11 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 13:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:11 INFO - 'HOME': '/Users/cltbld', 13:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:11 INFO - 'LOGNAME': 'cltbld', 13:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:11 INFO - 'MOZ_NO_REMOTE': '1', 13:12:11 INFO - 'NO_EM_RESTART': '1', 13:12:11 INFO - 'PAGER': '/bin/cat', 13:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:11 INFO - 'PWD': '/builds/slave/test', 13:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:11 INFO - 'SHELL': '/bin/bash', 13:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:11 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:11 INFO - 'USER': 'cltbld', 13:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:11 INFO - 'XPC_FLAGS': '0x0', 13:12:11 INFO - 'XPC_SERVICE_NAME': '0', 13:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:12 INFO - Downloading/unpacking psutil>=0.7.1 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:12:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:14 INFO - Installing collected packages: psutil 13:12:14 INFO - Running setup.py install for psutil 13:12:15 INFO - building 'psutil._psutil_osx' extension 13:12:15 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 13:12:15 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 13:12:15 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 13:12:15 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 13:12:15 INFO - building 'psutil._psutil_posix' extension 13:12:15 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 13:12:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:12:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:12:15 INFO - ^ 13:12:16 INFO - 1 warning generated. 13:12:16 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 13:12:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:16 INFO - Successfully installed psutil 13:12:16 INFO - Cleaning up... 13:12:16 INFO - Return code: 0 13:12:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:12:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:16 INFO - 'HOME': '/Users/cltbld', 13:12:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:16 INFO - 'LOGNAME': 'cltbld', 13:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:16 INFO - 'MOZ_NO_REMOTE': '1', 13:12:16 INFO - 'NO_EM_RESTART': '1', 13:12:16 INFO - 'PAGER': '/bin/cat', 13:12:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:16 INFO - 'PWD': '/builds/slave/test', 13:12:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:16 INFO - 'SHELL': '/bin/bash', 13:12:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:16 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:16 INFO - 'USER': 'cltbld', 13:12:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:16 INFO - 'XPC_FLAGS': '0x0', 13:12:16 INFO - 'XPC_SERVICE_NAME': '0', 13:12:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:12:16 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 13:12:16 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 13:12:16 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 13:12:16 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 13:12:16 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 13:12:16 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 13:12:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:12:18 INFO - Installing collected packages: mozsystemmonitor 13:12:18 INFO - Running setup.py install for mozsystemmonitor 13:12:19 INFO - Successfully installed mozsystemmonitor 13:12:19 INFO - Cleaning up... 13:12:19 INFO - Return code: 0 13:12:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:12:19 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')]} 13:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:19 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')]} 13:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:19 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:19 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 13:12:19 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 13:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:19 INFO - 'HOME': '/Users/cltbld', 13:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:19 INFO - 'LOGNAME': 'cltbld', 13:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:19 INFO - 'MOZ_NO_REMOTE': '1', 13:12:19 INFO - 'NO_EM_RESTART': '1', 13:12:19 INFO - 'PAGER': '/bin/cat', 13:12:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:19 INFO - 'PWD': '/builds/slave/test', 13:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:19 INFO - 'SHELL': '/bin/bash', 13:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:19 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:19 INFO - 'USER': 'cltbld', 13:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:19 INFO - 'XPC_FLAGS': '0x0', 13:12:19 INFO - 'XPC_SERVICE_NAME': '0', 13:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:19 INFO - Downloading/unpacking blobuploader==1.2.4 13:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:21 INFO - Downloading blobuploader-1.2.4.tar.gz 13:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:12:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:12:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:12:22 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 13:12:22 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 13:12:22 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 13:12:22 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 13:12:22 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 13:12:22 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 13:12:23 INFO - Downloading docopt-0.6.1.tar.gz 13:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:12:23 INFO - Installing collected packages: blobuploader, requests, docopt 13:12:23 INFO - Running setup.py install for blobuploader 13:12:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:12:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:12:23 INFO - Running setup.py install for requests 13:12:23 INFO - Running setup.py install for docopt 13:12:23 INFO - Successfully installed blobuploader requests docopt 13:12:23 INFO - Cleaning up... 13:12:24 INFO - Return code: 0 13:12:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12: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')]} 13:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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')]} 13:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:24 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:24 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 13:12:24 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 13:12:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:24 INFO - 'HOME': '/Users/cltbld', 13:12:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:24 INFO - 'LOGNAME': 'cltbld', 13:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:24 INFO - 'MOZ_NO_REMOTE': '1', 13:12:24 INFO - 'NO_EM_RESTART': '1', 13:12:24 INFO - 'PAGER': '/bin/cat', 13:12:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:24 INFO - 'PWD': '/builds/slave/test', 13:12:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:24 INFO - 'SHELL': '/bin/bash', 13:12:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:24 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:24 INFO - 'USER': 'cltbld', 13:12:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:24 INFO - 'XPC_FLAGS': '0x0', 13:12:24 INFO - 'XPC_SERVICE_NAME': '0', 13:12:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Ityzzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xoPcCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-QKRGk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-XMEyp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-HRQBUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-fYKaUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-mhlbKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-SVM_Vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-SCo5yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-9Zv6Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-YZRDE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-_bMlY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-70OPUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-LlNP_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ceyCKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-GDug85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-qxfuiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:12:26 INFO - Running setup.py install for manifestparser 13:12:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:12:26 INFO - Running setup.py install for mozcrash 13:12:26 INFO - Running setup.py install for mozdebug 13:12:26 INFO - Running setup.py install for mozdevice 13:12:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:12:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:12:26 INFO - Running setup.py install for mozfile 13:12:26 INFO - Running setup.py install for mozhttpd 13:12:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:12:26 INFO - Running setup.py install for mozinfo 13:12:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Running setup.py install for mozInstall 13:12:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Running setup.py install for mozleak 13:12:27 INFO - Running setup.py install for mozlog 13:12:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Running setup.py install for moznetwork 13:12:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Running setup.py install for mozprocess 13:12:27 INFO - Running setup.py install for mozprofile 13:12:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Running setup.py install for mozrunner 13:12:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Running setup.py install for mozscreenshot 13:12:28 INFO - Running setup.py install for moztest 13:12:28 INFO - Running setup.py install for mozversion 13:12:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:12:28 INFO - Cleaning up... 13:12:28 INFO - Return code: 0 13:12:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12: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')]} 13:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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')]} 13:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:28 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:28 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 13:12:28 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 13:12:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:28 INFO - 'HOME': '/Users/cltbld', 13:12:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:28 INFO - 'LOGNAME': 'cltbld', 13:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:28 INFO - 'MOZ_NO_REMOTE': '1', 13:12:28 INFO - 'NO_EM_RESTART': '1', 13:12:28 INFO - 'PAGER': '/bin/cat', 13:12:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:28 INFO - 'PWD': '/builds/slave/test', 13:12:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:28 INFO - 'SHELL': '/bin/bash', 13:12:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:28 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:28 INFO - 'USER': 'cltbld', 13:12:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:28 INFO - 'XPC_FLAGS': '0x0', 13:12:28 INFO - 'XPC_SERVICE_NAME': '0', 13:12:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:28 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-h3MI52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-JI3Dj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Y30ZBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-_280qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-3TGkTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-vpLAuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-6RzM_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-yp994L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-aYJ0lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-9pCMJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:29 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)) 13:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:29 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-kpchoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:30 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)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:30 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-e9ja8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:30 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)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:30 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-fZYAFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:30 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)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:30 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-F22TZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:30 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)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:30 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-QS3Knu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:30 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)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:30 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-LUWhya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:30 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)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:30 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-kKMNUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:30 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)) 13:12:30 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)) 13:12:30 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)) 13:12:30 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)) 13:12:30 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)) 13:12:30 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)) 13:12:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:34 INFO - Downloading blessings-1.6.tar.gz 13:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:12:34 INFO - Installing collected packages: blessings 13:12:34 INFO - Running setup.py install for blessings 13:12:34 INFO - Successfully installed blessings 13:12:34 INFO - Cleaning up... 13:12:34 INFO - Return code: 0 13:12:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:12:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:34 INFO - Reading from file tmpfile_stdout 13:12:34 INFO - Current package versions: 13:12:34 INFO - blessings == 1.6 13:12:34 INFO - blobuploader == 1.2.4 13:12:34 INFO - docopt == 0.6.1 13:12:34 INFO - manifestparser == 1.1 13:12:34 INFO - mozInstall == 1.12 13:12:34 INFO - mozcrash == 0.17 13:12:34 INFO - mozdebug == 0.1 13:12:34 INFO - mozdevice == 0.48 13:12:34 INFO - mozfile == 1.2 13:12:34 INFO - mozhttpd == 0.7 13:12:34 INFO - mozinfo == 0.9 13:12:34 INFO - mozleak == 0.1 13:12:34 INFO - mozlog == 3.1 13:12:34 INFO - moznetwork == 0.27 13:12:34 INFO - mozprocess == 0.22 13:12:34 INFO - mozprofile == 0.28 13:12:34 INFO - mozrunner == 6.11 13:12:34 INFO - mozscreenshot == 0.1 13:12:34 INFO - mozsystemmonitor == 0.0 13:12:34 INFO - moztest == 0.7 13:12:34 INFO - mozversion == 1.4 13:12:34 INFO - psutil == 3.1.1 13:12:34 INFO - requests == 1.2.3 13:12:34 INFO - wsgiref == 0.1.2 13:12:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:34 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:34 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 13:12:34 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 13:12:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:34 INFO - 'HOME': '/Users/cltbld', 13:12:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:34 INFO - 'LOGNAME': 'cltbld', 13:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:34 INFO - 'MOZ_NO_REMOTE': '1', 13:12:34 INFO - 'NO_EM_RESTART': '1', 13:12:34 INFO - 'PAGER': '/bin/cat', 13:12:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:34 INFO - 'PWD': '/builds/slave/test', 13:12:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:34 INFO - 'SHELL': '/bin/bash', 13:12:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:34 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:34 INFO - 'USER': 'cltbld', 13:12:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:34 INFO - 'XPC_FLAGS': '0x0', 13:12:34 INFO - 'XPC_SERVICE_NAME': '0', 13:12:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 INFO - Cleaning up... 13:12:35 INFO - Return code: 0 13:12:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:12:35 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')]} 13:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:35 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')]} 13:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:35 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:35 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 13:12:35 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 13:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:35 INFO - 'HOME': '/Users/cltbld', 13:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:35 INFO - 'LOGNAME': 'cltbld', 13:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:35 INFO - 'MOZ_NO_REMOTE': '1', 13:12:35 INFO - 'NO_EM_RESTART': '1', 13:12:35 INFO - 'PAGER': '/bin/cat', 13:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:35 INFO - 'PWD': '/builds/slave/test', 13:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:35 INFO - 'SHELL': '/bin/bash', 13:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:35 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:35 INFO - 'USER': 'cltbld', 13:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:35 INFO - 'XPC_FLAGS': '0x0', 13:12:35 INFO - 'XPC_SERVICE_NAME': '0', 13:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:35 INFO - Downloading/unpacking jsonschema 13:12:35 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 13:12:35 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 13:12:35 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 13:12:35 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 13:12:35 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 13:12:35 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 13:12:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:12:37 INFO - Downloading/unpacking functools32 (from jsonschema) 13:12:37 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 13:12:37 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 13:12:37 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 13:12:37 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 13:12:37 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 13:12:37 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 13:12:38 INFO - Downloading functools32-3.2.3-2.tar.gz 13:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:12:38 INFO - warning: no files found matching '*.txt' 13:12:38 INFO - no previously-included directories found matching 'build' 13:12:38 INFO - no previously-included directories found matching 'dist' 13:12:38 INFO - no previously-included directories found matching '.git*' 13:12:38 INFO - Installing collected packages: jsonschema, functools32 13:12:38 INFO - Running setup.py install for functools32 13:12:38 INFO - warning: no files found matching '*.txt' 13:12:38 INFO - no previously-included directories found matching 'build' 13:12:38 INFO - no previously-included directories found matching 'dist' 13:12:38 INFO - no previously-included directories found matching '.git*' 13:12:38 INFO - Successfully installed jsonschema functools32 13:12:38 INFO - Cleaning up... 13:12:38 INFO - Return code: 0 13:12:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:12:38 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')]} 13:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:38 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')]} 13:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:38 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 0x10eb1da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9c853dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, '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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 13:12:38 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 13:12:38 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 13:12:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:12:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:38 INFO - 'HOME': '/Users/cltbld', 13:12:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:38 INFO - 'LOGNAME': 'cltbld', 13:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:38 INFO - 'MOZ_NO_REMOTE': '1', 13:12:38 INFO - 'NO_EM_RESTART': '1', 13:12:38 INFO - 'PAGER': '/bin/cat', 13:12:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:38 INFO - 'PWD': '/builds/slave/test', 13:12:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:38 INFO - 'SHELL': '/bin/bash', 13:12:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:12:38 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:12:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:38 INFO - 'USER': 'cltbld', 13:12:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:38 INFO - 'XPC_FLAGS': '0x0', 13:12:38 INFO - 'XPC_SERVICE_NAME': '0', 13:12:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:38 INFO - Downloading/unpacking flake8 13:12:38 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 13:12:38 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 13:12:38 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 13:12:38 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 13:12:38 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 13:12:38 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 13:12:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:12:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:12:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:41 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 13:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:12:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:12:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:12:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:12:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:12:42 INFO - no previously-included directories found matching 'docs/_build' 13:12:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:12:42 INFO - Running setup.py install for pep8 13:12:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:12:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:12:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:12:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:12:42 INFO - no previously-included directories found matching 'docs/_build' 13:12:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:12:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:12:42 INFO - Cleaning up... 13:12:42 INFO - Return code: 0 13:12:42 INFO - Running post-action listener: _resource_record_post_action 13:12:42 INFO - Running post-action listener: _start_resource_monitoring 13:12:42 INFO - Starting resource monitoring. 13:12:42 INFO - ##### 13:12:42 INFO - ##### Running install step. 13:12:42 INFO - ##### 13:12:42 INFO - Running pre-action listener: _resource_record_pre_action 13:12:42 INFO - Running main action method: install 13:12:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:42 INFO - Reading from file tmpfile_stdout 13:12:42 INFO - Detecting whether we're running mozinstall >=1.0... 13:12:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:12:43 INFO - Reading from file tmpfile_stdout 13:12:43 INFO - Output received: 13:12:43 INFO - Usage: mozinstall [options] installer 13:12:43 INFO - Options: 13:12:43 INFO - -h, --help show this help message and exit 13:12:43 INFO - -d DEST, --destination=DEST 13:12:43 INFO - Directory to install application into. [default: 13:12:43 INFO - "/builds/slave/test"] 13:12:43 INFO - --app=APP Application being installed. [default: firefox] 13:12:43 INFO - mkdir: /builds/slave/test/build/application 13:12:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:13:17 INFO - Reading from file tmpfile_stdout 13:13:17 INFO - Output received: 13:13:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:13:17 INFO - Running post-action listener: _resource_record_post_action 13:13:17 INFO - ##### 13:13:17 INFO - ##### Running run-tests step. 13:13:17 INFO - ##### 13:13:17 INFO - Running pre-action listener: _resource_record_pre_action 13:13:17 INFO - Running main action method: run_tests 13:13:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:13:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:13:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:13:17 INFO - 2016-04-21 13:13:17.216 screenresolution[1640:7810] starting screenresolution argv=screenresolution get 13:13:17 INFO - 2016-04-21 13:13:17.219 screenresolution[1640:7810] Display 0: 1600x1200x32@60 13:13:17 INFO - 2016-04-21 13:13:17.224 screenresolution[1641:7812] starting screenresolution argv=screenresolution list 13:13:17 INFO - Available Modes on Display 0 13:13:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:13:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:13:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:13:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:13:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:13:17 INFO - Intel Iris: 13:13:17 INFO - Chipset Model: Intel Iris 13:13:17 INFO - Type: GPU 13:13:17 INFO - Bus: Built-In 13:13:17 INFO - VRAM (Dynamic, Max): 1536 MB 13:13:17 INFO - Vendor: Intel (0x8086) 13:13:17 INFO - Device ID: 0x0a2e 13:13:17 INFO - Revision ID: 0x0009 13:13:17 INFO - Displays: 13:13:17 INFO - Display: 13:13:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:13:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:13:17 INFO - Display Serial Number: 1600x1200 60 13:13:17 INFO - Main Display: Yes 13:13:17 INFO - Mirror: Off 13:13:17 INFO - Online: Yes 13:13:17 INFO - Rotation: Supported 13:13:17 INFO - Return code: 0 13:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:13:17 INFO - Python 2.7.3 13:13:17 INFO - Return code: 0 13:13:17 INFO - grabbing minidump binary from tooltool 13:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:17 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 0x10e9bce00>, '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 0x10e825db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:13:17 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 13:13:17 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 13:13:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:13:17 INFO - Return code: 0 13:13:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:13:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:13:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:13:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:13:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:13:18 INFO - Return code: 0 13:13:18 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:13:18 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0026 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nw2w9Jyp4i/Render', 13:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:18 INFO - 'HOME': '/Users/cltbld', 13:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:18 INFO - 'LOGNAME': 'cltbld', 13:13:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:13:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:18 INFO - 'MOZ_NO_REMOTE': '1', 13:13:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:13:18 INFO - 'NO_EM_RESTART': '1', 13:13:18 INFO - 'PAGER': '/bin/cat', 13:13:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:18 INFO - 'PWD': '/builds/slave/test', 13:13:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:18 INFO - 'SHELL': '/bin/bash', 13:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rpVOmXvC8g/Listeners', 13:13:18 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 13:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:18 INFO - 'USER': 'cltbld', 13:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:18 INFO - 'XPC_FLAGS': '0x0', 13:13:18 INFO - 'XPC_SERVICE_NAME': '0', 13:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:13:18 INFO - mozversion application_buildid: 20160421110016 13:13:18 INFO - mozversion application_changeset: fed6d478bf3d7cc80fbcf975361a2e2bc8e4ed30 13:13:18 INFO - mozversion application_display_name: Nightly 13:13:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:13:18 INFO - mozversion application_name: Firefox 13:13:18 INFO - mozversion application_remotingname: firefox 13:13:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:13:18 INFO - mozversion application_vendor: Mozilla 13:13:18 INFO - mozversion application_version: 48.0a1 13:13:18 INFO - mozversion platform_buildid: 20160421110016 13:13:18 INFO - mozversion platform_changeset: fed6d478bf3d7cc80fbcf975361a2e2bc8e4ed30 13:13:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:13:18 INFO - mozversion platform_version: 48.0a1 13:13:18 INFO - using testdate: 1461269598 13:13:18 INFO - actual date: 1461269598 13:13:18 INFO - starting webserver on 'localhost:49275' 13:13:18 INFO - SUITE-START | Running 2 tests 13:13:18 INFO - TEST-START | dromaeo_css 13:13:18 INFO - operating with platform_type : mac_ 13:13:18 INFO - Initialising browser for dromaeo_css test... 13:13:18 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpwuc6fj/profile http://localhost:49275/getInfo.html) 13:13:21 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 13:13:21 INFO - PROCESS | 1659 | colorDepth:24 13:13:21 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 13:13:21 INFO - PROCESS | 1659 | __metrics 13:13:21 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:13:22 INFO - TEST-INFO | 1659: exit 0 13:13:22 INFO - Browser initialized. 13:13:22 INFO - Running cycle 1/1 for dromaeo_css test... 13:13:22 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpwuc6fj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:13:34 INFO - PROCESS | 1661 | RSS: Main: 247955456 13:13:34 INFO - PROCESS | 1661 | 13:14:24 INFO - PROCESS | 1661 | 1461269664010 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] 13:16:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 13:16:36 INFO - PROCESS | 1661 | RSS: Main: 260059136 13:16:36 INFO - PROCESS | 1661 | 13:19:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 13:19:39 INFO - PROCESS | 1661 | RSS: Main: 271683584 13:19:39 INFO - PROCESS | 1661 | 13:22:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 13:22:41 INFO - PROCESS | 1661 | RSS: Main: 274001920 13:22:41 INFO - PROCESS | 1661 | 13:25:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 13:25:43 INFO - PROCESS | 1661 | RSS: Main: 275529728 13:25:43 INFO - PROCESS | 1661 | 13:28:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 13:28:47 INFO - PROCESS | 1661 | RSS: Main: 278396928 13:28:47 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 13:31:49 INFO - PROCESS | 1661 | RSS: Main: 279650304 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | __start_tp_report 13:31:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 13:31:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 13:31:49 INFO - PROCESS | 1661 | |i|pagename|runs| 13:31:49 INFO - PROCESS | 1661 | |0;dojo.html;3454.9309013819716;3517.8065206413644;3521;3543.681068703816;3601.81140223726;4962.503749625044;5597.188407680889;5921.615095018821;5935.792247271346;5997.220361353024;5862;5923.3484316725235;5932.139839723249;5986.970065149679;6040.667763273018;5267.947320526793;5315.0698627740185;5351.983123206578;5367.516923476886;5397.649152805075;39.84485840104656;5356.437574054727;5365.624406291561;5379.139337705968;5431.429699881515;5293.788248470057;5308.018016666929;5327.973360133194;5333.146696528556;5340.252364668911;5283.577313814885;5414.512693857571;5420.295361603005;5461.2354270402175;5484.177373393983;5247.711375874325;5273.393559740641;5344.385395679507;5371.919421208685;5389.514943655051;5655.519280861132;5774.364819869811;5793.942060579382;5806.216160818278;5873.85315367115;6191.504679625664;6192.411720886508;6200.379962003809;6235.251769787655;6256.280527739323;6253.562250642457;6298.4646305064125;6299.275583307933;6301.936980630181;6304.401081897213;6024.728887200086;6035.366286539894;6043.1841701370195;6044.667543285162;6045.72794224261;6279.780207692709;6341.112244285804;6389.872202555923;6429.164208652939;6433.517486188554;5174.97412512935;6228.128062071313;6263;6263.592866463665;6276.246850377893;5175.611829112831;5206.55744261742;6251;6292.46514046302;6331.398517140864;6158.322584515699;6165.537584681167;6189.102580125857;6247.3440288769025;6251.78118765841;6163.753449862045;6177.62934223948;6237;6257.624542527463;6259.812205633838;4240.321548552279;4980.6513544052295;5042.445331013586;5051.06555287273;5057.69653820772;3480.686738193575;4384.583464570861;4503.414556107685;4538.614217791524;4549.954500455019;5185.22221666753;5810.099434587646;5940.732667029994;5942.732577034044;6013.51891848651;6314.463270621956;6315.526335524854;6348.111264422892;6352.809415717536;6357.682115894279;5843.035899076604;5892.558058145656;5903.203067585907;5907.172995780508;5924.31870334917;5481.561475081984;5534.833954981357;5535.8892822144135;5545.667259964415;5554.527865131509;6371.331010243857;6531.934680653228;6546.9345306547275;6555.868882622321;6562.507811914125;6287.119803227643;6302.243730752156;6310.49516038725;6325.209348831396;6333.398327158912;6511.088447617242;6512.967435162794;6518.282988871315;6529.575577587441;6529.9020514691365;6169.660668663267;6221.377862213923;6233.314335423191;6264;6266;6225.657588832477;6267.2165979252595;6303.842403939938;6309.90535141964;6312.968435157978;6157.907631385622;6235.719392627252;6255.593386429868;6268.874622507616;6291.182146320949;5251.921221181762;5849.970750146222;6144.095330447575;6187.133801267736;6213.9067913982235;5953.940460595339;6095.756169753337;6127.417895299939;6136.048912418582;6177.907331390123;4775.904481910412;6155.507559395148;6200.906986395117;6219.844503887439;6239.188905442264;5213.687178769286;5859.5019423349495;6063.242094738158;6096.664683442454;6180.072989051679 13:31:49 INFO - PROCESS | 1661 | |1;ext.html;521.8017153481676;576.0293904770463;587.5652017507047;605.4762630324767;638.9456896163823;37795.622043779586;38679.419808702856;38737.806310968444;38756.80621596895;38771;39342.80328598353;40270;40290;40312.798436007775;40374.39438408426;9325.347225694204;9503.334766566342;9582.616695332179;9613.279004074688;9618.759531011745;8256.463329883538;9595.184409325217;9665.806683866354;9692.90307096927;9772.267079968995;8558.78603034926;9577.377470464433;9622.615095396211;9704.320697551175;9708.611655533805;9592.08875156859;9657.323987320855;9676;9677.90322096777;9697.757556061084;8342.957130358705;9115.361924665276;9500.619975201053;9604.567794449267;9617.855732163953;33270.83364583186;39611;39618.40572391416;39727.8013609933;39780.20439591192;2597.402597402608;2816.7042460541757;3058.7705922055934;3275.3449310138067;3395.4906763985355;26271.211863644123;26600.802963866674;26811.731882681117;27284;27333.316667083283;2576.4589436218416;2576.9355766105996;2621.8164728469014;2627.4482358704695;2834.829910205374;327092;329052;330066;330126.9999999976;330469;34179.65820341815;34610.13474663103;34686.826565867515;34696.653033469345;34878.302433951176;30790.53814192789;35004.64995350065;35292.823535882155;35360;35389.292214155575;14524.346404411826;14685;14759.188244646648;14828.777568336483;14868.70262594742;12318.692032699075;12365.07261955339;12566.434510447052;12689.175203611736;12797.488100476063;7941.404394670423;7990.840183196304;8134.552599607078;8207.015158181057;8216.876746848802;3380.4084285249983;3405.6594340565744;3446.6208717041104;3462.497937799005;3471.479278108304;991.7669347703284;1057.7408534908843;1159.171192597297;1164.2083383299437;1203.7833190025879;28677.713222867504;29916.96121023634;29948.251293717833;30554.236144096132;30748.53877191844;1057.7677006287743;1062.5377960587168;1084.430673896195;1178.4461303187488;1268.9809652855215;15893.920530397274;16289;16355.86073365842;16720.247588858532;16897.15514224284;615008;624159;634345;643906;645617;58635;58871;59394.10908836371;59469;59653;1428.8142541469542;1461.9634509137356;1482.2070192446988;1512.100300321285;1519.3163076615347;29671.25821854471;30293.091207263817;30461.62922668441;30629.98405103633;30641.62112704889;52723.736381317845;53061;53221;53543.46456535385;53851;590701;593050.0347498235;598425;599019;599187;26529.60205596879;26741.06406275771;26859.731402685724;41036.38445423261;42356.36465453082;38016.42975355427;43377.783111085504;43414.782926085165;43721.56278437175;44120.779396102815;35773;35972.46041309435;36033;36417.63582364142;36905.26189476142;35932.64067359293;36073;36195.63804362028;36633;36698.8165059173;32011.519827203076;35654.28691426209;36391.45412818757;36688.63311366852;36749;29892.551611726278;31297;32093.358132836747;32197.356052878342;35315.646843531234;21241.256556020464;21635.783642163376;24072.638910416008;24764.876175619007;25321 13:31:49 INFO - PROCESS | 1661 | |2;jquery.html;346.4041848021403;387.6724168077974;396.95402614111805;406.89420750604836;415.1013056732175;17437.912810435944;19675.803241967576;20836.270730524433;21023.57952840946;21432.464188395297;17213.397531086415;19928.60142797147;20020.799792002075;20304;20538.281160159397;5903.173555702205;5935.940640593603;5959.612625179371;6101.511879049687;6125.295591007024;5988.431099045583;6054.636721796706;6065.454109130177;6067.696615169246;6074.72663730131;5782.392848750884;5894.381089985554;6016.819495415145;6018.458338749511;6023.36754640763;5866.20806191163;5879.794207202727;5922.763089476437;5988.700564971756;5996.16053752475;5221.164613661834;5276.261323414725;5287.180487024518;5289.9471005290225;5296.867578310535;9687.41875487473;25877.870610646827;26270.21189364322;26433.603495947576;26517;2192.682061101147;2285.5771682238837;2335.3577766114286;2366.2782851230368;2403.6274377471345;9752.21982241419;10166.034226748447;10242.334248273839;11859.229150105215;13637.113587616872;1447.7321695486369;1721.8106008339073;1767.5846176148596;1774.902380369079;1941.9126139323623;92324;109740;129089.48186813417;129230;133328;11198.776024479464;11273.943630281796;11663.708407289885;11754.70613234659;11996.160268781101;14753.631159220891;14919.254037298091;15223.391064357524;15267.770983435257;15294.311755971008;8670.089640587774;8858.911410885808;8862.778430539289;8889.244414224733;8969.14793094655;8623.353248506388;8724.040355560883;8730.345224108218;8752.299816014704;8842.513661748666;3833.386658134685;3877.3020856245585;4261.509926358446;4302.440682711227;4710.222813235846;1986.960260794805;2024.7165396844453;2026.523766914774;2041.5916816636436;2049.764277108122;390.68940192546995;407.24659380146494;426.7204980737622;776.4681193382551;947.5262368815593;11375.203735738585;11878.990285825628;11919.106067045006;11937.343446110373;12069.0344772418;396.541993996934;442.126799570845;461.93301971213987;470.901110766736;474.4638558428953;5586.692731899784;5840.620359676607;6043.728032238472;6187.535934804908;6196.504279657616;574778;576888;595211;606299;615115;125213;136437.6356236425;138153;141947.87078193834;143940;434.42221844946147;435.7603318174991;463.069230846002;468.4846668664442;474.09684550930535;9562.474063926551;10899.910008999037;11190.832137518106;11332.376719280519;11494.425278736197;48723.75638121787;51619.74190129175;52027.47972520226;52416;52999.47000529945;77667.223327766;79101.60449197717;79581;80172.99567510858;81525;8237.546934918637;8312.5428101455;8314.376421768271;8453.027901791127;8720.825583488167;12152.453139608915;12266.938665306616;12552.686182845502;12658.98728101768;12885.935570322088;10925.562977480811;11175.776484470103;11290.209685322277;11469.426528673699;11470.713232169262;10621.044106030495;10951.959563841423;10974.835377469504;11147.665570032912;11343.943280283545;10382.754069511708;10643.893561064291;11155.665330040112;11699.415029248674;11705.765884682429;6567.770128045496;8308.418410711434;8374.790630234293;8393.790155246168;8397.748067557983;11108.944455277671;11260.09919206478;11406.20156589064;11568.537258509565;11571.942140289244 13:31:49 INFO - PROCESS | 1661 | |3;mootools.html;1408.5140626483867;1647.8846480746345;1857.600615867589;1974.4471547966566;1989.9403017909467;8027.237412445808;8391.24478796908;8622.137786221374;8635.093315201908;8661.393702440831;8496.660133594663;8524.275436587883;8566.143385661431;8612.440191387572;8689.695860644879;2469.506098780242;2491.962620560684;2492.3395300245447;2497.7627125423055;2498.2380373985925;2421.903123875043;2426.648136020276;2432.975670243293;2435.2937648082034;2436.658867758515;2460.667809845666;2462.113639089926;2513.3088400689776;2521;2535.7210706822234;2467.432490527171;2475.3687809608523;2476.5789815731277;2485.2295788305682;2485.490474452733;2428.1380110061027;2458.8032957363366;2476.962845557318;2488.502299540099;2499.387650025737;8933.59798809055;9030.413023153475;9035.909640903572;9037;9057.864132038025;5220;5418.9458105418835;5441.83674489766;5449.972750136264;5474.972625136849;3585.480105384731;3599.5860476045327;3626.9274614507826;3643.708503319728;3659.652333028358;1041.6770801051623;1051.5215576912487;1053.336398069219;1067.3169171730099;1068.4657671164418;11568.421578921105;11683.649490515298;11693.824592631117;11700.531978720926;11725.765484690344;4647.953520464752;4686.906261874743;4844.249141383091;4859.927101093486;4923.286123512071;9980.201583873273;9988.950055249677;10003.649872254582;10015.44760562114;10021.99780021992;5340.412554618989;5365.141577347606;5379.058664733656;5413.593980451482;5414.187871819258;4475.440569928759;4830.855074347775;4885.5358740919555;4892.902141957212;4954.876128096755;3914.3345631242755;3922.7842468663926;4004.3192657248337;4026.1343811080174;4062.532808727034;1210.509743553862;1379.5723325769043;1383.5434306678771;1385.9951535136945;1387.7432674955166;804.9597520124088;871.3595507302127;909.508865212791;947.1948843483123;948.9561482369477;5916.3787802281;6009.338972712998;6017;6051.213342265477;6157.110421479512;442.5530214483331;458.68580022683904;472.29156265601597;473.92419208396745;475.01434523364156;3981.5620281768984;4021.738586991836;4062.2281766464275;4067.593240675909;4075.0219947212454;10445.529951152217;10844.403557804397;10880.78238435227;10947.56209751601;10977;9130.726078217664;10580.841287380696;10657.147428205724;10831.945840270748;10871.130309575216;7010.369066784014;8251.752447426587;8262.049864265566;8312.8753068704;8313.916860831314;5761.530809643514;5874.148248504029;5900.970495147497;5949.405059494016;5984.730687119003;9967.50162491887;9987.700368988943;10007.749806254611;10036.498175091363;10195.847062293924;9719.65981190655;9826.75433114178;10266.024727650862;10285.125764310118;10315.865254821814;6822.658867056528;7214.458915581457;7386.926130738624;7482.2517748224745;7532.924670753441;6880.58716477013;8119.431639785158;8183.304419124441;8186.54973976437;8207.261346478846;7142.392896603847;7260.818479538054;7274.96362518184;7385.261473852786;7405.407567394488;7095.751648692271;7142.428605711634;7204.135503739585;7257.0565826442225;7264.745733899288;6840.452763778786;6845.828854278683;6868.484863635347;6939.99370091344;6950.965245173742;4766.285057241442;4839.080574690938;4903.485134060872;4950.331705219749;5057.797688092435;4826.613870890391;4899.926501102415;4959.008198360414;4965.106280869334;5089.8727531812 13:31:49 INFO - PROCESS | 1661 | |4;prototype.html;316.33569504041515;317.0583367398825;322.0499526397128;323.68602455618054;327.28814827749636;5748.310202775668;6058.727357268923;6205.689715514223;6315.652639104847;6318.273398559167;5245.055889939809;6143.201383820097;6212.130301757747;6235.666211982674;6257.937420625793;4386.473623165217;4544.84093056743;4609.608183304423;4663.65022623303;4678.134545109148;4356.259435895905;4399.64802815774;4598.26427771555;4678.298255261721;4702.482726900038;4259.6369161868215;4387.9561204387865;4653.9069218615605;4668.626509879202;4684.742339171345;3830.8276127574327;4316.482022157345;4471.8658440246845;4623.630109591241;4630.953690463086;3113.532970054487;3290.457074582691;3303.702666759979;3922.7254092213557;3935.291647503448;6091.634501929899;6113.083037544359;6114.877702445926;6132.816015519542;6133.355997620275;5163.044836705184;5384.273123128406;5391.919121213185;5415.160650099201;5441.727913604304;6105.389461053859;6617.9338206617795;6620.93498473668;6652.53432259742;6656.866862662768;3913.9823645852;4110.917781644351;4350.978245108786;4354.934675979863;4363.52001279859;6150.84622884427;7119.786406407816;7152.392046676039;7162.785116446515;7171.481966361159;4405.735655860659;4433.778311084433;4439.711418757771;4440.311751678495;4441.512093448666;4214.17823524416;4259.361095835588;4447.510773814878;4490.932636010462;4519.502854685982;5335.7047576701125;5354.116570765858;5356.3572371315695;5401.405845357008;5414.91877621836;6327.715252813542;6728.798136055926;6736.351917177132;6741.764038258638;6742.123523941857;1349.595121463557;1437.0300047467917;1524.329295110148;1529.7246495630893;1539.8614124728829;935.5462600638684;983.6753871222622;987.8123156600223;1002.7593377589326;1005.5726316315682;6160.691965401712;6183.350748171467;6211.254649442096;6218.906716399258;6221.253449586078;6310.368963103653;6560.343965603402;6620.668966551653;6973.232944376115;6982.546134501241;5052.520010598987;5401.650282127287;6115.510759139258;6147.538934579924;6203.991064333794;3481.89554313371;3583.623719509414;3625.510556074949;3639.6178401268016;3696.8521259149334;7001.964990175016;7184.461165412615;7195.352418282379;7273.781786546412;7281.854362912712;6011;6207.131001659771;6968.303169682991;7137.002458967247;7161.641917904188;6488.961766117399;6778.694958726869;6938.271481494471;7214.386777124003;7252.041948673723;5712.828615141552;6550;6788.524803263922;6905.170129915677;6930.7227710891;5617.606767526234;6280.5289603279025;6436.420722135031;6732.461403087644;6767.898481522682;5939.9308124538;6210.844728881814;7013.438924886099;7023.051887995055;7241.5293005954445;4397.450318710161;4406.206882761134;4414.183376075436;4424.292113261991;4445.755483448442;5310.495502927216;6013.895136702119;6108.08378743192;6430.260520040255;7036.542624729376;5234.528892399702;5917.615355001911;6782.491313151435;6793.558418702769;6816.216135144324;5543.833684989457;5951.206877658471;6163.7842675506145;6742.898856517058;6783.18601767791;6330;6372.87254254903;6579.967100164468;6628.237752658506;6658.467322614082;5548.278723765885;6692.498062645418;6695.966520167368;6701.262861085179;6720.663966801738;6251.6874156292915;6572.441342485751;6575.4410875076155;6584.242812076673;6604.966975165094 13:31:49 INFO - PROCESS | 1661 | |5;yui.html;611.9143319935208;650.4242112245201;678.057500074896;685.595498655793;729.5476804381284;14806.555803325891;14833.035852669582;14886.851131488682;14995.925020374896;15018.774718379233;13610.251436171007;13747.931260343685;13804.792928106086;13823.37794799234;13872.306384680776;908.2189317187214;926.7080869526111;928.2666693312275;928.3826255540058;928.7585227840742;721.6752461392394;888.2361169394316;907.3648446087732;924.1313165624292;927.2674587076202;943.1228957069923;944.4191822029437;947.5735918836551;966.1208300446596;971.03382134776;937.610891480035;943.5235206220883;960.1982344742148;960.8991055939131;971.2666936462996;696.03946553756;709.3225969199453;709.3296834491408;710.4174576846988;716.9354758071746;14320.42718291267;14394.208318542476;14514.346854391577;14678.412863485448;14745.262736863115;10384.79230415395;12665.873341266562;12705.174163679423;12801;13128.2123072615;9748.853767193497;10045.89954100457;10051.447170405625;10436.79126417475;10497.107745841613;3455.3089382123676;3489.930201395983;3504.176518518146;3530.9293814123575;3606.5852426970973;76442;76513.61743191248;77548.22451775522;78405.21594784036;78918.60540697374;7715.421343399267;8807.999999999873;9461.19579835708;9576.616935322509;9729.318947673595;21541.138354465646;21864.23475178393;21937.451563711034;21942.232021879157;21966.341009769734;7132.750353737594;7216.242294559101;7336.303051210233;7354.96322518384;7355.448341374418;3138.5135304027913;3960.4653371795016;3988.122613025188;4150.543440221573;4165;3579.302036102987;3601.3697602919383;3632.6730594246646;3647.0699971507624;3668.4313931340685;622.1787240842045;649.6849028221309;651.739304278283;657.7927952694886;660.6630618384659;508.8804131029205;534.1720333483086;575.2139606521773;601.0803470689851;605.9151718759377;6684.7028521798;7915.960420197977;8060.919390806134;8167.550784706898;8199.303059240032;533.0857579251525;558.2630927176169;572.6983937599741;583.76941108262;588.7438964050651;3752.3433399155037;3812.752171108869;3840.8655697050467;3928.548216955087;3981.5620281768984;86080;86818.13181868101;86868.5656571713;88093.11906880849;89181.55409222913;71745;74097;74171.25828741751;74349;74792.25207747961;65158;65560;66212.3446913831;67056;67787.98318025135;7498.250174982458;7519.172890982097;7645.006149200568;7668.079830420162;7801.29788319054;36771;40871.79564102161;40933.79533102315;43131.568684312755;43186.35220471633;55679.16481252865;62973.37026629858;63150.68424657847;63303.36696632975;63812.61873812581;9976.102150806462;10106.646267380607;10140.797184056426;10283.33158344735;10486.423246721199;10746.355218686904;10823.783524329627;11052.944735276273;11179.161562882653;11282.548698051985;10227.232957528066;10246.948765256126;10261.127804136433;10296.86734459224;10302.484875756332;8272.586370681562;10238.692839214835;10258.28192026551;10496.842547361643;10564;11330.943345283551;11438.485268162785;11747.941260293645;11886.762264754829;11997.160198786001;9562.426254424758;9568.09103135201;9692.75768105803;9824.950875245579;9912.950435247778;9666.95166524191;9694.175995040501;9843.606255749975;9882.802343953224;9997.500124993576 13:31:49 INFO - PROCESS | 1661 | __end_tp_report 13:31:49 INFO - PROCESS | 1661 | __start_cc_report 13:31:49 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,396 13:31:49 INFO - PROCESS | 1661 | __end_cc_report 13:31:49 INFO - PROCESS | 1661 | __startTimestamp1461270709912__endTimestamp 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | ------- Summary: start ------- 13:31:49 INFO - PROCESS | 1661 | Number of tests: 6 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5773.51 Median:6044.67 stddev:802.45 (13.3%) stddev-sans-first:784.03 13:31:49 INFO - PROCESS | 1661 | Values: 3454.9 3517.8 3521.0 3543.7 3601.8 4962.5 5597.2 5921.6 5935.8 5997.2 5862.0 5923.3 5932.1 5987.0 6040.7 5267.9 5315.1 5352.0 5367.5 5397.6 39.8 5356.4 5365.6 5379.1 5431.4 5293.8 5308.0 5328.0 5333.1 5340.3 5283.6 5414.5 5420.3 5461.2 5484.2 5247.7 5273.4 5344.4 5371.9 5389.5 5655.5 5774.4 5793.9 5806.2 5873.9 6191.5 6192.4 6200.4 6235.3 6256.3 6253.6 6298.5 6299.3 6301.9 6304.4 6024.7 6035.4 6043.2 6044.7 6045.7 6279.8 6341.1 6389.9 6429.2 6433.5 5175.0 6228.1 6263.0 6263.6 6276.2 5175.6 5206.6 6251.0 6292.5 6331.4 6158.3 6165.5 6189.1 6247.3 6251.8 6163.8 6177.6 6237.0 6257.6 6259.8 4240.3 4980.7 5042.4 5051.1 5057.7 3480.7 4384.6 4503.4 4538.6 4550.0 5185.2 5810.1 5940.7 5942.7 6013.5 6314.5 6315.5 6348.1 6352.8 6357.7 5843.0 5892.6 5903.2 5907.2 5924.3 5481.6 5534.8 5535.9 5545.7 5554.5 6371.3 6531.9 6546.9 6555.9 6562.5 6287.1 6302.2 6310.5 6325.2 6333.4 6511.1 6513.0 6518.3 6529.6 6529.9 6169.7 6221.4 6233.3 6264.0 6266.0 6225.7 6267.2 6303.8 6309.9 6313.0 6157.9 6235.7 6255.6 6268.9 6291.2 5251.9 5850.0 6144.1 6187.1 6213.9 5953.9 6095.8 6127.4 6136.0 6177.9 4775.9 6155.5 6200.9 6219.8 6239.2 5213.7 5859.5 6063.2 6096.7 6180.1 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:63751.63 Median:35852.82 stddev:144089.18 (401.9%) stddev-sans-first:144415.18 13:31:49 INFO - PROCESS | 1661 | Values: 521.8 576.0 587.6 605.5 638.9 37795.6 38679.4 38737.8 38756.8 38771.0 39342.8 40270.0 40290.0 40312.8 40374.4 9325.3 9503.3 9582.6 9613.3 9618.8 8256.5 9595.2 9665.8 9692.9 9772.3 8558.8 9577.4 9622.6 9704.3 9708.6 9592.1 9657.3 9676.0 9677.9 9697.8 8343.0 9115.4 9500.6 9604.6 9617.9 33270.8 39611.0 39618.4 39727.8 39780.2 2597.4 2816.7 3058.8 3275.3 3395.5 26271.2 26600.8 26811.7 27284.0 27333.3 2576.5 2576.9 2621.8 2627.4 2834.8 327092.0 329052.0 330066.0 330127.0 330469.0 34179.7 34610.1 34686.8 34696.7 34878.3 30790.5 35004.6 35292.8 35360.0 35389.3 14524.3 14685.0 14759.2 14828.8 14868.7 12318.7 12365.1 12566.4 12689.2 12797.5 7941.4 7990.8 8134.6 8207.0 8216.9 3380.4 3405.7 3446.6 3462.5 3471.5 991.8 1057.7 1159.2 1164.2 1203.8 28677.7 29917.0 29948.3 30554.2 30748.5 1057.8 1062.5 1084.4 1178.4 1269.0 15893.9 16289.0 16355.9 16720.2 16897.2 615008.0 624159.0 634345.0 643906.0 645617.0 58635.0 58871.0 59394.1 59469.0 59653.0 1428.8 1462.0 1482.2 1512.1 1519.3 29671.3 30293.1 30461.6 30630.0 30641.6 52723.7 53061.0 53221.0 53543.5 53851.0 590701.0 593050.0 598425.0 599019.0 599187.0 26529.6 26741.1 26859.7 41036.4 42356.4 38016.4 43377.8 43414.8 43721.6 44120.8 35773.0 35972.5 36033.0 36417.6 36905.3 35932.6 36073.0 36195.6 36633.0 36698.8 32011.5 35654.3 36391.5 36688.6 36749.0 29892.6 31297.0 32093.4 32197.4 35315.6 21241.3 21635.8 24072.6 24764.9 25321.0 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:34513.61 Median:393.62 stddev:99620.15 (25309.0%) stddev-sans-first:99866.57 13:31:49 INFO - PROCESS | 1661 | Values: 346.4 387.7 397.0 406.9 415.1 17437.9 19675.8 20836.3 21023.6 21432.5 17213.4 19928.6 20020.8 20304.0 20538.3 5903.2 5935.9 5959.6 6101.5 6125.3 5988.4 6054.6 6065.5 6067.7 6074.7 5782.4 5894.4 6016.8 6018.5 6023.4 5866.2 5879.8 5922.8 5988.7 5996.2 5221.2 5276.3 5287.2 5289.9 5296.9 9687.4 25877.9 26270.2 26433.6 26517.0 2192.7 2285.6 2335.4 2366.3 2403.6 9752.2 10166.0 10242.3 11859.2 13637.1 1447.7 1721.8 1767.6 1774.9 1941.9 92324.0 109740.0 129089.5 129230.0 133328.0 11198.8 11273.9 11663.7 11754.7 11996.2 14753.6 14919.3 15223.4 15267.8 15294.3 8670.1 8858.9 8862.8 8889.2 8969.1 8623.4 8724.0 8730.3 8752.3 8842.5 3833.4 3877.3 4261.5 4302.4 4710.2 1987.0 2024.7 2026.5 2041.6 2049.8 390.7 407.2 426.7 776.5 947.5 11375.2 11879.0 11919.1 11937.3 12069.0 396.5 442.1 461.9 470.9 474.5 5586.7 5840.6 6043.7 6187.5 6196.5 574778.0 576888.0 595211.0 606299.0 615115.0 125213.0 136437.6 138153.0 141947.9 143940.0 434.4 435.8 463.1 468.5 474.1 9562.5 10899.9 11190.8 11332.4 11494.4 48723.8 51619.7 52027.5 52416.0 52999.5 77667.2 79101.6 79581.0 80173.0 81525.0 8237.5 8312.5 8314.4 8453.0 8720.8 12152.5 12266.9 12552.7 12659.0 12885.9 10925.6 11175.8 11290.2 11469.4 11470.7 10621.0 10952.0 10974.8 11147.7 11343.9 10382.8 10643.9 11155.7 11699.4 11705.8 6567.8 8308.4 8374.8 8393.8 8397.7 11108.9 11260.1 11406.2 11568.5 11571.9 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5706.27 Median:4852.09 stddev:3169.35 (65.3%) stddev-sans-first:3161.78 13:31:49 INFO - PROCESS | 1661 | Values: 1408.5 1647.9 1857.6 1974.4 1989.9 8027.2 8391.2 8622.1 8635.1 8661.4 8496.7 8524.3 8566.1 8612.4 8689.7 2469.5 2492.0 2492.3 2497.8 2498.2 2421.9 2426.6 2433.0 2435.3 2436.7 2460.7 2462.1 2513.3 2521.0 2535.7 2467.4 2475.4 2476.6 2485.2 2485.5 2428.1 2458.8 2477.0 2488.5 2499.4 8933.6 9030.4 9035.9 9037.0 9057.9 5220.0 5418.9 5441.8 5450.0 5475.0 3585.5 3599.6 3626.9 3643.7 3659.7 1041.7 1051.5 1053.3 1067.3 1068.5 11568.4 11683.6 11693.8 11700.5 11725.8 4648.0 4686.9 4844.2 4859.9 4923.3 9980.2 9989.0 10003.6 10015.4 10022.0 5340.4 5365.1 5379.1 5413.6 5414.2 4475.4 4830.9 4885.5 4892.9 4954.9 3914.3 3922.8 4004.3 4026.1 4062.5 1210.5 1379.6 1383.5 1386.0 1387.7 805.0 871.4 909.5 947.2 949.0 5916.4 6009.3 6017.0 6051.2 6157.1 442.6 458.7 472.3 473.9 475.0 3981.6 4021.7 4062.2 4067.6 4075.0 10445.5 10844.4 10880.8 10947.6 10977.0 9130.7 10580.8 10657.1 10831.9 10871.1 7010.4 8251.8 8262.0 8312.9 8313.9 5761.5 5874.1 5901.0 5949.4 5984.7 9967.5 9987.7 10007.7 10036.5 10195.8 9719.7 9826.8 10266.0 10285.1 10315.9 6822.7 7214.5 7386.9 7482.3 7532.9 6880.6 8119.4 8183.3 8186.5 8207.3 7142.4 7260.8 7275.0 7385.3 7405.4 7095.8 7142.4 7204.1 7257.1 7264.7 6840.5 6845.8 6868.5 6940.0 6951.0 4766.3 4839.1 4903.5 4950.3 5057.8 4826.6 4899.9 4959.0 4965.1 5089.9 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:5329.69 Median:6110.58 stddev:1705.91 (27.9%) stddev-sans-first:1668.68 13:31:49 INFO - PROCESS | 1661 | Values: 316.3 317.1 322.0 323.7 327.3 5748.3 6058.7 6205.7 6315.7 6318.3 5245.1 6143.2 6212.1 6235.7 6257.9 4386.5 4544.8 4609.6 4663.7 4678.1 4356.3 4399.6 4598.3 4678.3 4702.5 4259.6 4388.0 4653.9 4668.6 4684.7 3830.8 4316.5 4471.9 4623.6 4631.0 3113.5 3290.5 3303.7 3922.7 3935.3 6091.6 6113.1 6114.9 6132.8 6133.4 5163.0 5384.3 5391.9 5415.2 5441.7 6105.4 6617.9 6620.9 6652.5 6656.9 3914.0 4110.9 4351.0 4354.9 4363.5 6150.8 7119.8 7152.4 7162.8 7171.5 4405.7 4433.8 4439.7 4440.3 4441.5 4214.2 4259.4 4447.5 4490.9 4519.5 5335.7 5354.1 5356.4 5401.4 5414.9 6327.7 6728.8 6736.4 6741.8 6742.1 1349.6 1437.0 1524.3 1529.7 1539.9 935.5 983.7 987.8 1002.8 1005.6 6160.7 6183.4 6211.3 6218.9 6221.3 6310.4 6560.3 6620.7 6973.2 6982.5 5052.5 5401.7 6115.5 6147.5 6204.0 3481.9 3583.6 3625.5 3639.6 3696.9 7002.0 7184.5 7195.4 7273.8 7281.9 6011.0 6207.1 6968.3 7137.0 7161.6 6489.0 6778.7 6938.3 7214.4 7252.0 5712.8 6550.0 6788.5 6905.2 6930.7 5617.6 6280.5 6436.4 6732.5 6767.9 5939.9 6210.8 7013.4 7023.1 7241.5 4397.5 4406.2 4414.2 4424.3 4445.8 5310.5 6013.9 6108.1 6430.3 7036.5 5234.5 5917.6 6782.5 6793.6 6816.2 5543.8 5951.2 6163.8 6742.9 6783.2 6330.0 6372.9 6580.0 6628.2 6658.5 5548.3 6692.5 6696.0 6701.3 6720.7 6251.7 6572.4 6575.4 6584.2 6605.0 13:31:49 INFO - PROCESS | 1661 | 13:31:49 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:17268.27 Median:63062.03 stddev:24059.11 (38.2%) stddev-sans-first:24094.09 13:31:49 INFO - PROCESS | 1661 | Values: 611.9 650.4 678.1 685.6 729.5 14806.6 14833.0 14886.9 14995.9 15018.8 13610.3 13747.9 13804.8 13823.4 13872.3 908.2 926.7 928.3 928.4 928.8 721.7 888.2 907.4 924.1 927.3 943.1 944.4 947.6 966.1 971.0 937.6 943.5 960.2 960.9 971.3 696.0 709.3 709.3 710.4 716.9 14320.4 14394.2 14514.3 14678.4 14745.3 10384.8 12665.9 12705.2 12801.0 13128.2 9748.9 10045.9 10051.4 10436.8 10497.1 3455.3 3489.9 3504.2 3530.9 3606.6 76442.0 76513.6 77548.2 78405.2 78918.6 7715.4 8808.0 9461.2 9576.6 9729.3 21541.1 21864.2 21937.5 21942.2 21966.3 7132.8 7216.2 7336.3 7355.0 7355.4 3138.5 3960.5 3988.1 4150.5 4165.0 3579.3 3601.4 3632.7 3647.1 3668.4 622.2 649.7 651.7 657.8 660.7 508.9 534.2 575.2 601.1 605.9 6684.7 7916.0 8060.9 8167.6 8199.3 533.1 558.3 572.7 583.8 588.7 3752.3 3812.8 3840.9 3928.5 3981.6 86080.0 86818.1 86868.6 88093.1 89181.6 71745.0 74097.0 74171.3 74349.0 74792.3 65158.0 65560.0 66212.3 67056.0 67788.0 7498.3 7519.2 7645.0 7668.1 7801.3 36771.0 40871.8 40933.8 43131.6 43186.4 55679.2 62973.4 63150.7 63303.4 63812.6 9976.1 10106.6 10140.8 10283.3 10486.4 10746.4 10823.8 11052.9 11179.2 11282.5 10227.2 10246.9 10261.1 10296.9 10302.5 8272.6 10238.7 10258.3 10496.8 10564.0 11330.9 11438.5 11747.9 11886.8 11997.2 9562.4 9568.1 9692.8 9825.0 9913.0 9667.0 9694.2 9843.6 9882.8 9997.5 13:31:49 INFO - PROCESS | 1661 | -------- Summary: end -------- 13:31:49 INFO - PROCESS | 1661 | 13:31:50 INFO - TEST-INFO | 1661: exit 0 13:31:50 INFO - TEST-OK | dromaeo_css | took 1111475ms 13:31:50 INFO - TEST-START | kraken 13:31:50 INFO - operating with platform_type : mac_ 13:31:50 INFO - Initialising browser for kraken test... 13:31:50 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp32hSAZ/profile http://localhost:49275/getInfo.html) 13:31:53 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 13:31:53 INFO - PROCESS | 1673 | colorDepth:24 13:31:53 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 13:31:53 INFO - PROCESS | 1673 | __metrics 13:31:53 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:31:54 INFO - TEST-INFO | 1673: exit 0 13:31:54 INFO - Browser initialized. 13:31:54 INFO - Running cycle 1/1 for kraken test... 13:31:54 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp32hSAZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:06 INFO - PROCESS | 1676 | RSS: Main: 248004608 13:32:06 INFO - PROCESS | 1676 | 13:32:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 13:32:09 INFO - PROCESS | 1676 | RSS: Main: 260038656 13:32:09 INFO - PROCESS | 1676 | 13:32:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 13:32:13 INFO - PROCESS | 1676 | RSS: Main: 268951552 13:32:13 INFO - PROCESS | 1676 | 13:32:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 13:32:17 INFO - PROCESS | 1676 | RSS: Main: 271941632 13:32:17 INFO - PROCESS | 1676 | 13:32:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 13:32:19 INFO - PROCESS | 1676 | RSS: Main: 273051648 13:32:19 INFO - PROCESS | 1676 | 13:32:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:32:22 INFO - PROCESS | 1676 | RSS: Main: 274792448 13:32:22 INFO - PROCESS | 1676 | 13:32:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 13:32:25 INFO - PROCESS | 1676 | RSS: Main: 274657280 13:32:25 INFO - PROCESS | 1676 | 13:32:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 13:32:29 INFO - PROCESS | 1676 | RSS: Main: 274890752 13:32:29 INFO - PROCESS | 1676 | 13:32:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 13:32:33 INFO - PROCESS | 1676 | RSS: Main: 274976768 13:32:33 INFO - PROCESS | 1676 | 13:32:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:32:35 INFO - PROCESS | 1676 | RSS: Main: 274825216 13:32:35 INFO - PROCESS | 1676 | 13:32:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:32:37 INFO - PROCESS | 1676 | RSS: Main: 275025920 13:32:37 INFO - PROCESS | 1676 | 13:32:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:32:40 INFO - PROCESS | 1676 | RSS: Main: 275271680 13:32:40 INFO - PROCESS | 1676 | 13:32:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:32:44 INFO - PROCESS | 1676 | RSS: Main: 275296256 13:32:44 INFO - PROCESS | 1676 | 13:32:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:32:47 INFO - PROCESS | 1676 | RSS: Main: 275337216 13:32:47 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 13:32:49 INFO - PROCESS | 1676 | RSS: Main: 276987904 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | __start_tp_report 13:32:49 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 13:32:49 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 13:32:49 INFO - PROCESS | 1676 | |i|pagename|runs| 13:32:49 INFO - PROCESS | 1676 | |0;ai-astar;118;110;138;151;173;175;115;115;116;114 13:32:49 INFO - PROCESS | 1676 | |1;audio-beat-detection;161;128;149;170;112;169;171;151;171;123 13:32:49 INFO - PROCESS | 1676 | |2;audio-dft;193;209;226;204;197;209;195;194;197;198 13:32:49 INFO - PROCESS | 1676 | |3;audio-fft;69;80;73;135;60;77;97;76;88;48 13:32:49 INFO - PROCESS | 1676 | |4;audio-oscillator;80;84;126;76;80;92;121;80;83;123 13:32:49 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;84;90;93;89;93;89;92;90;79;127 13:32:49 INFO - PROCESS | 1676 | |6;imaging-darkroom;98;99;98;119;151;118;99;98;98;98 13:32:49 INFO - PROCESS | 1676 | |7;imaging-desaturate;91;93;141;88;90;91;89;127;145;146 13:32:49 INFO - PROCESS | 1676 | |8;json-parse-financial;62;63;62;63;61;62;62;74;63;62 13:32:49 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;66;69;72;65;73;72;65;65;65;68 13:32:49 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;84;84;83;86;84;84;84;83;99;108 13:32:49 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;156;157;163;151;145;140;140;138;198;170 13:32:49 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;172;177;175;173;177;173;183;184;173;175 13:32:49 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;73;90;66;69;69;68;69;70;67;71 13:32:49 INFO - PROCESS | 1676 | __end_tp_report 13:32:49 INFO - PROCESS | 1676 | __start_cc_report 13:32:49 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1041 13:32:49 INFO - PROCESS | 1676 | __end_cc_report 13:32:49 INFO - PROCESS | 1676 | __startTimestamp1461270769864__endTimestamp 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | ------- Summary: start ------- 13:32:49 INFO - PROCESS | 1676 | Number of tests: 14 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:132.50 Median:128.00 stddev:25.30 (19.8%) stddev-sans-first:26.29 13:32:49 INFO - PROCESS | 1676 | Values: 118.0 110.0 138.0 151.0 173.0 175.0 115.0 115.0 116.0 114.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:150.50 Median:165.00 stddev:22.16 (13.4%) stddev-sans-first:23.18 13:32:49 INFO - PROCESS | 1676 | Values: 161.0 128.0 149.0 170.0 112.0 169.0 171.0 151.0 171.0 123.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:202.20 Median:201.00 stddev:10.21 (5.1%) stddev-sans-first:10.27 13:32:49 INFO - PROCESS | 1676 | Values: 193.0 209.0 226.0 204.0 197.0 209.0 195.0 194.0 197.0 198.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:80.30 Median:76.50 stddev:23.56 (30.8%) stddev-sans-first:24.63 13:32:49 INFO - PROCESS | 1676 | Values: 69.0 80.0 73.0 135.0 60.0 77.0 97.0 76.0 88.0 48.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:80.00 stddev:20.35 (25.4%) stddev-sans-first:20.90 13:32:49 INFO - PROCESS | 1676 | Values: 80.0 84.0 126.0 76.0 80.0 92.0 121.0 80.0 83.0 123.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:90.00 stddev:12.83 (14.3%) stddev-sans-first:13.23 13:32:49 INFO - PROCESS | 1676 | Values: 84.0 90.0 93.0 89.0 93.0 89.0 92.0 90.0 79.0 127.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:98.00 stddev:17.42 (17.8%) stddev-sans-first:18.12 13:32:49 INFO - PROCESS | 1676 | Values: 98.0 99.0 98.0 119.0 151.0 118.0 99.0 98.0 98.0 98.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:110.10 Median:89.50 stddev:26.05 (29.1%) stddev-sans-first:26.70 13:32:49 INFO - PROCESS | 1676 | Values: 91.0 93.0 141.0 88.0 90.0 91.0 89.0 127.0 145.0 146.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:62.50 stddev:3.78 (6.0%) stddev-sans-first:3.97 13:32:49 INFO - PROCESS | 1676 | Values: 62.0 63.0 62.0 63.0 61.0 62.0 62.0 74.0 63.0 62.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.50 stddev:3.30 (4.8%) stddev-sans-first:3.42 13:32:49 INFO - PROCESS | 1676 | Values: 66.0 69.0 72.0 65.0 73.0 72.0 65.0 65.0 65.0 68.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:84.00 stddev:8.53 (10.2%) stddev-sans-first:8.93 13:32:49 INFO - PROCESS | 1676 | Values: 84.0 84.0 83.0 86.0 84.0 84.0 84.0 83.0 99.0 108.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:155.80 Median:156.50 stddev:18.23 (11.6%) stddev-sans-first:19.34 13:32:49 INFO - PROCESS | 1676 | Values: 156.0 157.0 163.0 151.0 145.0 140.0 140.0 138.0 198.0 170.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:176.00 stddev:4.21 (2.4%) stddev-sans-first:4.18 13:32:49 INFO - PROCESS | 1676 | Values: 172.0 177.0 175.0 173.0 177.0 173.0 183.0 184.0 173.0 175.0 13:32:49 INFO - PROCESS | 1676 | 13:32:49 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:69.50 stddev:6.89 (9.9%) stddev-sans-first:7.28 13:32:49 INFO - PROCESS | 1676 | Values: 73.0 90.0 66.0 69.0 69.0 68.0 69.0 70.0 67.0 71.0 13:32:49 INFO - PROCESS | 1676 | -------- Summary: end -------- 13:32:49 INFO - PROCESS | 1676 | 13:32:50 INFO - TEST-INFO | 1676: exit 0 13:32:50 INFO - TEST-OK | kraken | took 60367ms 13:32:50 INFO - SUITE-END | took 1171s 13:32:51 INFO - Completed test suite (00:19:32) 13:32:51 INFO - javascript benchmark 13:32:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3454.9309013819716, 3517.8065206413644, 3521.0, 3543.681068703816, 3601.81140223726, 4962.503749625044, 5597.188407680889, 5921.615095018821, 5935.792247271346, 5997.220361353024, 5862.0, 5923.3484316725235, 5932.139839723249, 5986.970065149679, 6040.667763273018, 5267.947320526793, 5315.0698627740185, 5351.983123206578, 5367.516923476886, 5397.649152805075, 39.84485840104656, 5356.437574054727, 5365.624406291561, 5379.139337705968, 5431.429699881515, 5293.788248470057, 5308.018016666929, 5327.973360133194, 5333.146696528556, 5340.252364668911, 5283.577313814885, 5414.512693857571, 5420.295361603005, 5461.2354270402175, 5484.177373393983, 5247.711375874325, 5273.393559740641, 5344.385395679507, 5371.919421208685, 5389.514943655051, 5655.519280861132, 5774.364819869811, 5793.942060579382, 5806.216160818278, 5873.85315367115, 6191.504679625664, 6192.411720886508, 6200.379962003809, 6235.251769787655, 6256.280527739323, 6253.562250642457, 6298.4646305064125, 6299.275583307933, 6301.936980630181, 6304.401081897213, 6024.728887200086, 6035.366286539894, 6043.1841701370195, 6044.667543285162, 6045.72794224261, 6279.780207692709, 6341.112244285804, 6389.872202555923, 6429.164208652939, 6433.517486188554, 5174.97412512935, 6228.128062071313, 6263.0, 6263.592866463665, 6276.246850377893, 5175.611829112831, 5206.55744261742, 6251.0, 6292.46514046302, 6331.398517140864, 6158.322584515699, 6165.537584681167, 6189.102580125857, 6247.3440288769025, 6251.78118765841, 6163.753449862045, 6177.62934223948, 6237.0, 6257.624542527463, 6259.812205633838, 4240.321548552279, 4980.6513544052295, 5042.445331013586, 5051.06555287273, 5057.69653820772, 3480.686738193575, 4384.583464570861, 4503.414556107685, 4538.614217791524, 4549.954500455019, 5185.22221666753, 5810.099434587646, 5940.732667029994, 5942.732577034044, 6013.51891848651, 6314.463270621956, 6315.526335524854, 6348.111264422892, 6352.809415717536, 6357.682115894279, 5843.035899076604, 5892.558058145656, 5903.203067585907, 5907.172995780508, 5924.31870334917, 5481.561475081984, 5534.833954981357, 5535.8892822144135, 5545.667259964415, 5554.527865131509, 6371.331010243857, 6531.934680653228, 6546.9345306547275, 6555.868882622321, 6562.507811914125, 6287.119803227643, 6302.243730752156, 6310.49516038725, 6325.209348831396, 6333.398327158912, 6511.088447617242, 6512.967435162794, 6518.282988871315, 6529.575577587441, 6529.9020514691365, 6169.660668663267, 6221.377862213923, 6233.314335423191, 6264.0, 6266.0, 6225.657588832477, 6267.2165979252595, 6303.842403939938, 6309.90535141964, 6312.968435157978, 6157.907631385622, 6235.719392627252, 6255.593386429868, 6268.874622507616, 6291.182146320949, 5251.921221181762, 5849.970750146222, 6144.095330447575, 6187.133801267736, 6213.9067913982235, 5953.940460595339, 6095.756169753337, 6127.417895299939, 6136.048912418582, 6177.907331390123, 4775.904481910412, 6155.507559395148, 6200.906986395117, 6219.844503887439, 6239.188905442264, 5213.687178769286, 5859.5019423349495, 6063.242094738158, 6096.664683442454, 6180.072989051679], "value": 5727.481440882335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.8017153481676, 576.0293904770463, 587.5652017507047, 605.4762630324767, 638.9456896163823, 37795.622043779586, 38679.419808702856, 38737.806310968444, 38756.80621596895, 38771.0, 39342.80328598353, 40270.0, 40290.0, 40312.798436007775, 40374.39438408426, 9325.347225694204, 9503.334766566342, 9582.616695332179, 9613.279004074688, 9618.759531011745, 8256.463329883538, 9595.184409325217, 9665.806683866354, 9692.90307096927, 9772.267079968995, 8558.78603034926, 9577.377470464433, 9622.615095396211, 9704.320697551175, 9708.611655533805, 9592.08875156859, 9657.323987320855, 9676.0, 9677.90322096777, 9697.757556061084, 8342.957130358705, 9115.361924665276, 9500.619975201053, 9604.567794449267, 9617.855732163953, 33270.83364583186, 39611.0, 39618.40572391416, 39727.8013609933, 39780.20439591192, 2597.402597402608, 2816.7042460541757, 3058.7705922055934, 3275.3449310138067, 3395.4906763985355, 26271.211863644123, 26600.802963866674, 26811.731882681117, 27284.0, 27333.316667083283, 2576.4589436218416, 2576.9355766105996, 2621.8164728469014, 2627.4482358704695, 2834.829910205374, 327092.0, 329052.0, 330066.0, 330126.9999999976, 330469.0, 34179.65820341815, 34610.13474663103, 34686.826565867515, 34696.653033469345, 34878.302433951176, 30790.53814192789, 35004.64995350065, 35292.823535882155, 35360.0, 35389.292214155575, 14524.346404411826, 14685.0, 14759.188244646648, 14828.777568336483, 14868.70262594742, 12318.692032699075, 12365.07261955339, 12566.434510447052, 12689.175203611736, 12797.488100476063, 7941.404394670423, 7990.840183196304, 8134.552599607078, 8207.015158181057, 8216.876746848802, 3380.4084285249983, 3405.6594340565744, 3446.6208717041104, 3462.497937799005, 3471.479278108304, 991.7669347703284, 1057.7408534908843, 1159.171192597297, 1164.2083383299437, 1203.7833190025879, 28677.713222867504, 29916.96121023634, 29948.251293717833, 30554.236144096132, 30748.53877191844, 1057.7677006287743, 1062.5377960587168, 1084.430673896195, 1178.4461303187488, 1268.9809652855215, 15893.920530397274, 16289.0, 16355.86073365842, 16720.247588858532, 16897.15514224284, 615008.0, 624159.0, 634345.0, 643906.0, 645617.0, 58635.0, 58871.0, 59394.10908836371, 59469.0, 59653.0, 1428.8142541469542, 1461.9634509137356, 1482.2070192446988, 1512.100300321285, 1519.3163076615347, 29671.25821854471, 30293.091207263817, 30461.62922668441, 30629.98405103633, 30641.62112704889, 52723.736381317845, 53061.0, 53221.0, 53543.46456535385, 53851.0, 590701.0, 593050.0347498235, 598425.0, 599019.0, 599187.0, 26529.60205596879, 26741.06406275771, 26859.731402685724, 41036.38445423261, 42356.36465453082, 38016.42975355427, 43377.783111085504, 43414.782926085165, 43721.56278437175, 44120.779396102815, 35773.0, 35972.46041309435, 36033.0, 36417.63582364142, 36905.26189476142, 35932.64067359293, 36073.0, 36195.63804362028, 36633.0, 36698.8165059173, 32011.519827203076, 35654.28691426209, 36391.45412818757, 36688.63311366852, 36749.0, 29892.551611726278, 31297.0, 32093.358132836747, 32197.356052878342, 35315.646843531234, 21241.256556020464, 21635.783642163376, 24072.638910416008, 24764.876175619007, 25321.0], "value": 18090.125215026117, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.4041848021403, 387.6724168077974, 396.95402614111805, 406.89420750604836, 415.1013056732175, 17437.912810435944, 19675.803241967576, 20836.270730524433, 21023.57952840946, 21432.464188395297, 17213.397531086415, 19928.60142797147, 20020.799792002075, 20304.0, 20538.281160159397, 5903.173555702205, 5935.940640593603, 5959.612625179371, 6101.511879049687, 6125.295591007024, 5988.431099045583, 6054.636721796706, 6065.454109130177, 6067.696615169246, 6074.72663730131, 5782.392848750884, 5894.381089985554, 6016.819495415145, 6018.458338749511, 6023.36754640763, 5866.20806191163, 5879.794207202727, 5922.763089476437, 5988.700564971756, 5996.16053752475, 5221.164613661834, 5276.261323414725, 5287.180487024518, 5289.9471005290225, 5296.867578310535, 9687.41875487473, 25877.870610646827, 26270.21189364322, 26433.603495947576, 26517.0, 2192.682061101147, 2285.5771682238837, 2335.3577766114286, 2366.2782851230368, 2403.6274377471345, 9752.21982241419, 10166.034226748447, 10242.334248273839, 11859.229150105215, 13637.113587616872, 1447.7321695486369, 1721.8106008339073, 1767.5846176148596, 1774.902380369079, 1941.9126139323623, 92324.0, 109740.0, 129089.48186813417, 129230.0, 133328.0, 11198.776024479464, 11273.943630281796, 11663.708407289885, 11754.70613234659, 11996.160268781101, 14753.631159220891, 14919.254037298091, 15223.391064357524, 15267.770983435257, 15294.311755971008, 8670.089640587774, 8858.911410885808, 8862.778430539289, 8889.244414224733, 8969.14793094655, 8623.353248506388, 8724.040355560883, 8730.345224108218, 8752.299816014704, 8842.513661748666, 3833.386658134685, 3877.3020856245585, 4261.509926358446, 4302.440682711227, 4710.222813235846, 1986.960260794805, 2024.7165396844453, 2026.523766914774, 2041.5916816636436, 2049.764277108122, 390.68940192546995, 407.24659380146494, 426.7204980737622, 776.4681193382551, 947.5262368815593, 11375.203735738585, 11878.990285825628, 11919.106067045006, 11937.343446110373, 12069.0344772418, 396.541993996934, 442.126799570845, 461.93301971213987, 470.901110766736, 474.4638558428953, 5586.692731899784, 5840.620359676607, 6043.728032238472, 6187.535934804908, 6196.504279657616, 574778.0, 576888.0, 595211.0, 606299.0, 615115.0, 125213.0, 136437.6356236425, 138153.0, 141947.87078193834, 143940.0, 434.42221844946147, 435.7603318174991, 463.069230846002, 468.4846668664442, 474.09684550930535, 9562.474063926551, 10899.910008999037, 11190.832137518106, 11332.376719280519, 11494.425278736197, 48723.75638121787, 51619.74190129175, 52027.47972520226, 52416.0, 52999.47000529945, 77667.223327766, 79101.60449197717, 79581.0, 80172.99567510858, 81525.0, 8237.546934918637, 8312.5428101455, 8314.376421768271, 8453.027901791127, 8720.825583488167, 12152.453139608915, 12266.938665306616, 12552.686182845502, 12658.98728101768, 12885.935570322088, 10925.562977480811, 11175.776484470103, 11290.209685322277, 11469.426528673699, 11470.713232169262, 10621.044106030495, 10951.959563841423, 10974.835377469504, 11147.665570032912, 11343.943280283545, 10382.754069511708, 10643.893561064291, 11155.665330040112, 11699.415029248674, 11705.765884682429, 6567.770128045496, 8308.418410711434, 8374.790630234293, 8393.790155246168, 8397.748067557983, 11108.944455277671, 11260.09919206478, 11406.20156589064, 11568.537258509565, 11571.942140289244], "value": 8675.450166873266, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.5140626483867, 1647.8846480746345, 1857.600615867589, 1974.4471547966566, 1989.9403017909467, 8027.237412445808, 8391.24478796908, 8622.137786221374, 8635.093315201908, 8661.393702440831, 8496.660133594663, 8524.275436587883, 8566.143385661431, 8612.440191387572, 8689.695860644879, 2469.506098780242, 2491.962620560684, 2492.3395300245447, 2497.7627125423055, 2498.2380373985925, 2421.903123875043, 2426.648136020276, 2432.975670243293, 2435.2937648082034, 2436.658867758515, 2460.667809845666, 2462.113639089926, 2513.3088400689776, 2521.0, 2535.7210706822234, 2467.432490527171, 2475.3687809608523, 2476.5789815731277, 2485.2295788305682, 2485.490474452733, 2428.1380110061027, 2458.8032957363366, 2476.962845557318, 2488.502299540099, 2499.387650025737, 8933.59798809055, 9030.413023153475, 9035.909640903572, 9037.0, 9057.864132038025, 5220.0, 5418.9458105418835, 5441.83674489766, 5449.972750136264, 5474.972625136849, 3585.480105384731, 3599.5860476045327, 3626.9274614507826, 3643.708503319728, 3659.652333028358, 1041.6770801051623, 1051.5215576912487, 1053.336398069219, 1067.3169171730099, 1068.4657671164418, 11568.421578921105, 11683.649490515298, 11693.824592631117, 11700.531978720926, 11725.765484690344, 4647.953520464752, 4686.906261874743, 4844.249141383091, 4859.927101093486, 4923.286123512071, 9980.201583873273, 9988.950055249677, 10003.649872254582, 10015.44760562114, 10021.99780021992, 5340.412554618989, 5365.141577347606, 5379.058664733656, 5413.593980451482, 5414.187871819258, 4475.440569928759, 4830.855074347775, 4885.5358740919555, 4892.902141957212, 4954.876128096755, 3914.3345631242755, 3922.7842468663926, 4004.3192657248337, 4026.1343811080174, 4062.532808727034, 1210.509743553862, 1379.5723325769043, 1383.5434306678771, 1385.9951535136945, 1387.7432674955166, 804.9597520124088, 871.3595507302127, 909.508865212791, 947.1948843483123, 948.9561482369477, 5916.3787802281, 6009.338972712998, 6017.0, 6051.213342265477, 6157.110421479512, 442.5530214483331, 458.68580022683904, 472.29156265601597, 473.92419208396745, 475.01434523364156, 3981.5620281768984, 4021.738586991836, 4062.2281766464275, 4067.593240675909, 4075.0219947212454, 10445.529951152217, 10844.403557804397, 10880.78238435227, 10947.56209751601, 10977.0, 9130.726078217664, 10580.841287380696, 10657.147428205724, 10831.945840270748, 10871.130309575216, 7010.369066784014, 8251.752447426587, 8262.049864265566, 8312.8753068704, 8313.916860831314, 5761.530809643514, 5874.148248504029, 5900.970495147497, 5949.405059494016, 5984.730687119003, 9967.50162491887, 9987.700368988943, 10007.749806254611, 10036.498175091363, 10195.847062293924, 9719.65981190655, 9826.75433114178, 10266.024727650862, 10285.125764310118, 10315.865254821814, 6822.658867056528, 7214.458915581457, 7386.926130738624, 7482.2517748224745, 7532.924670753441, 6880.58716477013, 8119.431639785158, 8183.304419124441, 8186.54973976437, 8207.261346478846, 7142.392896603847, 7260.818479538054, 7274.96362518184, 7385.261473852786, 7405.407567394488, 7095.751648692271, 7142.428605711634, 7204.135503739585, 7257.0565826442225, 7264.745733899288, 6840.452763778786, 6845.828854278683, 6868.484863635347, 6939.99370091344, 6950.965245173742, 4766.285057241442, 4839.080574690938, 4903.485134060872, 4950.331705219749, 5057.797688092435, 4826.613870890391, 4899.926501102415, 4959.008198360414, 4965.106280869334, 5089.8727531812], "value": 4548.51091789068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.33569504041515, 317.0583367398825, 322.0499526397128, 323.68602455618054, 327.28814827749636, 5748.310202775668, 6058.727357268923, 6205.689715514223, 6315.652639104847, 6318.273398559167, 5245.055889939809, 6143.201383820097, 6212.130301757747, 6235.666211982674, 6257.937420625793, 4386.473623165217, 4544.84093056743, 4609.608183304423, 4663.65022623303, 4678.134545109148, 4356.259435895905, 4399.64802815774, 4598.26427771555, 4678.298255261721, 4702.482726900038, 4259.6369161868215, 4387.9561204387865, 4653.9069218615605, 4668.626509879202, 4684.742339171345, 3830.8276127574327, 4316.482022157345, 4471.8658440246845, 4623.630109591241, 4630.953690463086, 3113.532970054487, 3290.457074582691, 3303.702666759979, 3922.7254092213557, 3935.291647503448, 6091.634501929899, 6113.083037544359, 6114.877702445926, 6132.816015519542, 6133.355997620275, 5163.044836705184, 5384.273123128406, 5391.919121213185, 5415.160650099201, 5441.727913604304, 6105.389461053859, 6617.9338206617795, 6620.93498473668, 6652.53432259742, 6656.866862662768, 3913.9823645852, 4110.917781644351, 4350.978245108786, 4354.934675979863, 4363.52001279859, 6150.84622884427, 7119.786406407816, 7152.392046676039, 7162.785116446515, 7171.481966361159, 4405.735655860659, 4433.778311084433, 4439.711418757771, 4440.311751678495, 4441.512093448666, 4214.17823524416, 4259.361095835588, 4447.510773814878, 4490.932636010462, 4519.502854685982, 5335.7047576701125, 5354.116570765858, 5356.3572371315695, 5401.405845357008, 5414.91877621836, 6327.715252813542, 6728.798136055926, 6736.351917177132, 6741.764038258638, 6742.123523941857, 1349.595121463557, 1437.0300047467917, 1524.329295110148, 1529.7246495630893, 1539.8614124728829, 935.5462600638684, 983.6753871222622, 987.8123156600223, 1002.7593377589326, 1005.5726316315682, 6160.691965401712, 6183.350748171467, 6211.254649442096, 6218.906716399258, 6221.253449586078, 6310.368963103653, 6560.343965603402, 6620.668966551653, 6973.232944376115, 6982.546134501241, 5052.520010598987, 5401.650282127287, 6115.510759139258, 6147.538934579924, 6203.991064333794, 3481.89554313371, 3583.623719509414, 3625.510556074949, 3639.6178401268016, 3696.8521259149334, 7001.964990175016, 7184.461165412615, 7195.352418282379, 7273.781786546412, 7281.854362912712, 6011.0, 6207.131001659771, 6968.303169682991, 7137.002458967247, 7161.641917904188, 6488.961766117399, 6778.694958726869, 6938.271481494471, 7214.386777124003, 7252.041948673723, 5712.828615141552, 6550.0, 6788.524803263922, 6905.170129915677, 6930.7227710891, 5617.606767526234, 6280.5289603279025, 6436.420722135031, 6732.461403087644, 6767.898481522682, 5939.9308124538, 6210.844728881814, 7013.438924886099, 7023.051887995055, 7241.5293005954445, 4397.450318710161, 4406.206882761134, 4414.183376075436, 4424.292113261991, 4445.755483448442, 5310.495502927216, 6013.895136702119, 6108.08378743192, 6430.260520040255, 7036.542624729376, 5234.528892399702, 5917.615355001911, 6782.491313151435, 6793.558418702769, 6816.216135144324, 5543.833684989457, 5951.206877658471, 6163.7842675506145, 6742.898856517058, 6783.18601767791, 6330.0, 6372.87254254903, 6579.967100164468, 6628.237752658506, 6658.467322614082, 5548.278723765885, 6692.498062645418, 6695.966520167368, 6701.262861085179, 6720.663966801738, 6251.6874156292915, 6572.441342485751, 6575.4410875076155, 6584.242812076673, 6604.966975165094], "value": 4766.617382928367, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.9143319935208, 650.4242112245201, 678.057500074896, 685.595498655793, 729.5476804381284, 14806.555803325891, 14833.035852669582, 14886.851131488682, 14995.925020374896, 15018.774718379233, 13610.251436171007, 13747.931260343685, 13804.792928106086, 13823.37794799234, 13872.306384680776, 908.2189317187214, 926.7080869526111, 928.2666693312275, 928.3826255540058, 928.7585227840742, 721.6752461392394, 888.2361169394316, 907.3648446087732, 924.1313165624292, 927.2674587076202, 943.1228957069923, 944.4191822029437, 947.5735918836551, 966.1208300446596, 971.03382134776, 937.610891480035, 943.5235206220883, 960.1982344742148, 960.8991055939131, 971.2666936462996, 696.03946553756, 709.3225969199453, 709.3296834491408, 710.4174576846988, 716.9354758071746, 14320.42718291267, 14394.208318542476, 14514.346854391577, 14678.412863485448, 14745.262736863115, 10384.79230415395, 12665.873341266562, 12705.174163679423, 12801.0, 13128.2123072615, 9748.853767193497, 10045.89954100457, 10051.447170405625, 10436.79126417475, 10497.107745841613, 3455.3089382123676, 3489.930201395983, 3504.176518518146, 3530.9293814123575, 3606.5852426970973, 76442.0, 76513.61743191248, 77548.22451775522, 78405.21594784036, 78918.60540697374, 7715.421343399267, 8807.999999999873, 9461.19579835708, 9576.616935322509, 9729.318947673595, 21541.138354465646, 21864.23475178393, 21937.451563711034, 21942.232021879157, 21966.341009769734, 7132.750353737594, 7216.242294559101, 7336.303051210233, 7354.96322518384, 7355.448341374418, 3138.5135304027913, 3960.4653371795016, 3988.122613025188, 4150.543440221573, 4165.0, 3579.302036102987, 3601.3697602919383, 3632.6730594246646, 3647.0699971507624, 3668.4313931340685, 622.1787240842045, 649.6849028221309, 651.739304278283, 657.7927952694886, 660.6630618384659, 508.8804131029205, 534.1720333483086, 575.2139606521773, 601.0803470689851, 605.9151718759377, 6684.7028521798, 7915.960420197977, 8060.919390806134, 8167.550784706898, 8199.303059240032, 533.0857579251525, 558.2630927176169, 572.6983937599741, 583.76941108262, 588.7438964050651, 3752.3433399155037, 3812.752171108869, 3840.8655697050467, 3928.548216955087, 3981.5620281768984, 86080.0, 86818.13181868101, 86868.5656571713, 88093.11906880849, 89181.55409222913, 71745.0, 74097.0, 74171.25828741751, 74349.0, 74792.25207747961, 65158.0, 65560.0, 66212.3446913831, 67056.0, 67787.98318025135, 7498.250174982458, 7519.172890982097, 7645.006149200568, 7668.079830420162, 7801.29788319054, 36771.0, 40871.79564102161, 40933.79533102315, 43131.568684312755, 43186.35220471633, 55679.16481252865, 62973.37026629858, 63150.68424657847, 63303.36696632975, 63812.61873812581, 9976.102150806462, 10106.646267380607, 10140.797184056426, 10283.33158344735, 10486.423246721199, 10746.355218686904, 10823.783524329627, 11052.944735276273, 11179.161562882653, 11282.548698051985, 10227.232957528066, 10246.948765256126, 10261.127804136433, 10296.86734459224, 10302.484875756332, 8272.586370681562, 10238.692839214835, 10258.28192026551, 10496.842547361643, 10564.0, 11330.943345283551, 11438.485268162785, 11747.941260293645, 11886.762264754829, 11997.160198786001, 9562.426254424758, 9568.09103135201, 9692.75768105803, 9824.950875245579, 9912.950435247778, 9666.95166524191, 9694.175995040501, 9843.606255749975, 9882.802343953224, 9997.500124993576], "value": 6677.128581692726, "unit": "score"}], "name": "dromaeo_css", "value": 7118.694217572955, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 110.0, 138.0, 151.0, 173.0, 175.0, 115.0, 115.0, 116.0, 114.0], "value": 132.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 128.0, 149.0, 170.0, 112.0, 169.0, 171.0, 151.0, 171.0, 123.0], "value": 150.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 209.0, 226.0, 204.0, 197.0, 209.0, 195.0, 194.0, 197.0, 198.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 80.0, 73.0, 135.0, 60.0, 77.0, 97.0, 76.0, 88.0, 48.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 126.0, 76.0, 80.0, 92.0, 121.0, 80.0, 83.0, 123.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 93.0, 89.0, 93.0, 89.0, 92.0, 90.0, 79.0, 127.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 119.0, 151.0, 118.0, 99.0, 98.0, 98.0, 98.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 141.0, 88.0, 90.0, 91.0, 89.0, 127.0, 145.0, 146.0], "value": 110.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 61.0, 62.0, 62.0, 74.0, 63.0, 62.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 72.0, 65.0, 73.0, 72.0, 65.0, 65.0, 65.0, 68.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 86.0, 84.0, 84.0, 84.0, 83.0, 99.0, 108.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 163.0, 151.0, 145.0, 140.0, 140.0, 138.0, 198.0, 170.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 177.0, 175.0, 173.0, 177.0, 173.0, 183.0, 184.0, 173.0, 175.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 90.0, 66.0, 69.0, 69.0, 68.0, 69.0, 70.0, 67.0, 71.0], "value": 71.2, "unit": "score"}], "name": "kraken", "value": 1592.8000000000002, "alertThreshold": 2.0}]} 13:32:51 INFO - Return code: 0 13:32:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:32:51 ERROR - # TBPL SUCCESS # 13:32:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:51 INFO - 2016-04-21 13:32:51.205 screenresolution[1681:10868] starting screenresolution argv=screenresolution get 13:32:51 INFO - 2016-04-21 13:32:51.209 screenresolution[1681:10868] Display 0: 1600x1200x32@60 13:32:51 INFO - 2016-04-21 13:32:51.215 screenresolution[1682:10871] starting screenresolution argv=screenresolution list 13:32:51 INFO - Available Modes on Display 0 13:32:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:51 INFO - Intel Iris: 13:32:51 INFO - Chipset Model: Intel Iris 13:32:51 INFO - Type: GPU 13:32:51 INFO - Bus: Built-In 13:32:51 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:51 INFO - Vendor: Intel (0x8086) 13:32:51 INFO - Device ID: 0x0a2e 13:32:51 INFO - Revision ID: 0x0009 13:32:51 INFO - Displays: 13:32:51 INFO - Display: 13:32:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:51 INFO - Display Serial Number: 1600x1200 60 13:32:51 INFO - Main Display: Yes 13:32:51 INFO - Mirror: Off 13:32:51 INFO - Online: Yes 13:32:51 INFO - Rotation: Supported 13:32:51 INFO - Return code: 0 13:32:51 INFO - Running post-action listener: _resource_record_post_action 13:32:51 INFO - Running post-run listener: _resource_record_post_run 13:32:52 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 37881344; Write bytes: 694738944; Read time: 395; Write time: 2216 13:32:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 388317696; Write bytes: 381062656; Read time: 29536; Write time: 724 13:32:52 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 36058624; Write bytes: 295993856; Read time: 362; Write time: 1452 13:32:52 INFO - Running post-run listener: _upload_blobber_files 13:32:52 INFO - Blob upload gear active. 13:32:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:32:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:32:52 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'] 13:32:52 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 13:32:52 INFO - (blobuploader) - INFO - Open directory for files ... 13:32:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:32:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:32:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:53 INFO - (blobuploader) - INFO - Done attempting. 13:32:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:32:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:32:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:54 INFO - (blobuploader) - INFO - Done attempting. 13:32:54 INFO - (blobuploader) - INFO - Iteration through files over. 13:32:54 INFO - Return code: 0 13:32:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:32:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:32:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9baf03df681373d3e2f2f11081f4eb3ee6c507ce4e37f3d42b14f4fccd3f2aa6bdfba54481ad5d70e08e10dc19b166c1e28a7e137d3bf3e79d835c8699e80830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91bb4a86b83f0a43adc82fd4813e7c7ab9dc72f93b996fceb53f451b924f0127ea0d8006cc0e748e086f900076a99d11c5fab726d77deac639523880e1f33e5e"} 13:32:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:32:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:32:54 INFO - Contents: 13:32:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9baf03df681373d3e2f2f11081f4eb3ee6c507ce4e37f3d42b14f4fccd3f2aa6bdfba54481ad5d70e08e10dc19b166c1e28a7e137d3bf3e79d835c8699e80830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91bb4a86b83f0a43adc82fd4813e7c7ab9dc72f93b996fceb53f451b924f0127ea0d8006cc0e748e086f900076a99d11c5fab726d77deac639523880e1f33e5e"} 13:32:54 INFO - Running post-run listener: copy_logs_to_upload_dir 13:32:54 INFO - Copying logs to upload dir... 13:32:54 INFO - mkdir: /builds/slave/test/build/upload/logs 13:32:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.447391 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-21 13:32:54.843452) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 13:32:54.847081) ========= 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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9baf03df681373d3e2f2f11081f4eb3ee6c507ce4e37f3d42b14f4fccd3f2aa6bdfba54481ad5d70e08e10dc19b166c1e28a7e137d3bf3e79d835c8699e80830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91bb4a86b83f0a43adc82fd4813e7c7ab9dc72f93b996fceb53f451b924f0127ea0d8006cc0e748e086f900076a99d11c5fab726d77deac639523880e1f33e5e"} build_url:https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011126 build_url: 'https://queue.taskcluster.net/v1/task/EbgJ8dVlQlWBnpE6GDKUHg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9baf03df681373d3e2f2f11081f4eb3ee6c507ce4e37f3d42b14f4fccd3f2aa6bdfba54481ad5d70e08e10dc19b166c1e28a7e137d3bf3e79d835c8699e80830", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91bb4a86b83f0a43adc82fd4813e7c7ab9dc72f93b996fceb53f451b924f0127ea0d8006cc0e748e086f900076a99d11c5fab726d77deac639523880e1f33e5e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 13:32:54.874705) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:32:54.875049) ========= 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.Nw2w9Jyp4i/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.rpVOmXvC8g/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.005187 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:32:54.925052) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 13:32:54.925510) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 13:32:54.926106) ========= ========= Total master_lag: 5.67 =========