builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0011 starttime: 1462642779.75 results: success (0) buildid: 20160507093556 builduid: 5d4bb3a1fd194ff5b30b65fdc51f16c0 revision: 52749e84bf25fb6aed91ab1339b74cc0e7aae5e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:39.751721) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:39.752093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:39.752368) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:39.789973) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:39.790228) ========= 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.WsWaFfHXGG/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.9SomJDmK0N/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-07 10:39:39-- 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% 7.91M=0.001s 2016-05-07 10:39:39 (7.91 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154651 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:39.961326) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:39.961608) ========= 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.WsWaFfHXGG/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.9SomJDmK0N/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.020378 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:40.012723) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-07 10:39:40.013426) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 52749e84bf25fb6aed91ab1339b74cc0e7aae5e7 --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 52749e84bf25fb6aed91ab1339b74cc0e7aae5e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WsWaFfHXGG/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.9SomJDmK0N/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-07 10:39:40,087 truncating revision to first 12 chars 2016-05-07 10:39:40,087 Setting DEBUG logging. 2016-05-07 10:39:40,087 attempt 1/10 2016-05-07 10:39:40,087 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/52749e84bf25?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-07 10:39:40,838 unpacking tar archive at: fx-team-52749e84bf25/testing/mozharness/ program finished with exit code 0 elapsedTime=0.997708 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-07 10:39:41.029504) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:41.029842) ========= script_repo_revision: 52749e84bf25fb6aed91ab1339b74cc0e7aae5e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:41.030246) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:41.030586) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 10:39:41.044220) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-07 10:39:41.044549) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WsWaFfHXGG/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.9SomJDmK0N/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:39:41 INFO - MultiFileLogger online at 20160507 10:39:41 in /builds/slave/test 10:39:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:39:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:39:41 INFO - {'append_to_log': False, 10:39:41 INFO - 'base_work_dir': '/builds/slave/test', 10:39:41 INFO - 'blob_upload_branch': 'Fx-Team', 10:39:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:39:41 INFO - 'branch': 'Fx-Team', 10:39:41 INFO - 'buildbot_json_path': 'buildprops.json', 10:39:41 INFO - 'config_files': ('talos/mac_config.py',), 10:39:41 INFO - 'default_actions': ('clobber', 10:39:41 INFO - 'read-buildbot-config', 10:39:41 INFO - 'download-and-extract', 10:39:41 INFO - 'populate-webroot', 10:39:41 INFO - 'create-virtualenv', 10:39:41 INFO - 'install', 10:39:41 INFO - 'run-tests'), 10:39:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:39:41 INFO - 'download_minidump_stackwalk': True, 10:39:41 INFO - 'download_symbols': 'ondemand', 10:39:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:39:41 INFO - 'tooltool.py': '/tools/tooltool.py', 10:39:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:39:41 INFO - '/tools/misc-python/virtualenv.py')}, 10:39:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:39:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:39:41 INFO - 'installer_path': 'installer.exe', 10:39:41 INFO - 'log_level': 'info', 10:39:41 INFO - 'log_name': 'talos', 10:39:41 INFO - 'log_to_console': True, 10:39:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:39:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:39:41 INFO - 'opt_config_files': (), 10:39:41 INFO - 'pip_index': False, 10:39:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:39:41 INFO - 'cmd': ('bash', 10:39:41 INFO - '-c', 10:39:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:39:41 INFO - 'enabled': True, 10:39:41 INFO - 'halt_on_failure': False, 10:39:41 INFO - 'name': 'check_screen_resolution'},), 10:39:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:39:41 INFO - 'cmd': ('bash', 10:39:41 INFO - '-c', 10:39:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:39:41 INFO - 'enabled': True, 10:39:41 INFO - 'halt_on_failure': False, 10:39:41 INFO - 'name': 'check_screen_resolution'},), 10:39:41 INFO - 'run_cmd_checks_enabled': True, 10:39:41 INFO - 'sps_profile': False, 10:39:41 INFO - 'sps_profile_interval': 0, 10:39:41 INFO - 'suite': 'dromaeojs', 10:39:41 INFO - 'system_bits': '32', 10:39:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:39:41 INFO - 'title': 't-yosemite-r7-0011', 10:39:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:39:41 INFO - 'use_talos_json': True, 10:39:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:39:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:39:41 INFO - 'work_dir': 'build'} 10:39:41 INFO - ##### 10:39:41 INFO - ##### Running clobber step. 10:39:41 INFO - ##### 10:39:41 INFO - Running pre-action listener: _resource_record_pre_action 10:39:41 INFO - Running main action method: clobber 10:39:41 INFO - rmtree: /builds/slave/test/build 10:39:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:39:42 INFO - Running post-action listener: _resource_record_post_action 10:39:42 INFO - ##### 10:39:42 INFO - ##### Finished clobber step (success) 10:39:42 INFO - ##### 10:39:42 INFO - ##### 10:39:42 INFO - ##### Running read-buildbot-config step. 10:39:42 INFO - ##### 10:39:42 INFO - Running pre-action listener: _resource_record_pre_action 10:39:42 INFO - Running main action method: read_buildbot_config 10:39:42 INFO - Using buildbot properties: 10:39:42 INFO - { 10:39:42 INFO - "project": "", 10:39:42 INFO - "product": "firefox", 10:39:42 INFO - "script_repo_revision": "production", 10:39:42 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:39:42 INFO - "repository": "", 10:39:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:39:42 INFO - "buildid": "20160507093556", 10:39:42 INFO - "pgo_build": "False", 10:39:42 INFO - "basedir": "/builds/slave/test", 10:39:42 INFO - "buildnumber": 1, 10:39:42 INFO - "slavename": "t-yosemite-r7-0011", 10:39:42 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:39:42 INFO - "platform": "yosemite_r7", 10:39:42 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:39:42 INFO - "branch": "fx-team", 10:39:42 INFO - "repo_path": "integration/fx-team", 10:39:42 INFO - "revision": "52749e84bf25fb6aed91ab1339b74cc0e7aae5e7", 10:39:42 INFO - "stage_platform": "macosx64", 10:39:42 INFO - "builduid": "5d4bb3a1fd194ff5b30b65fdc51f16c0", 10:39:42 INFO - "slavebuilddir": "test" 10:39:42 INFO - } 10:39:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:39:42 INFO - Running post-action listener: _resource_record_post_action 10:39:42 INFO - ##### 10:39:42 INFO - ##### Finished read-buildbot-config step (success) 10:39:42 INFO - ##### 10:39:42 INFO - ##### 10:39:42 INFO - ##### Running download-and-extract step. 10:39:42 INFO - ##### 10:39:42 INFO - Running pre-action listener: _resource_record_pre_action 10:39:42 INFO - Running main action method: download_and_extract 10:39:42 INFO - mkdir: /builds/slave/test/build/tests 10:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:39:42 INFO - https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:39:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:39:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:39:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:39:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 10:39:45 INFO - Downloaded 1199 bytes. 10:39:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:39:45 INFO - Using the following test package requirements: 10:39:45 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:39:45 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:39:45 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:39:45 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'jsshell-mac.zip'], 10:39:45 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:39:45 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:39:45 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:39:45 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:39:45 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:39:45 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:39:45 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:39:45 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:39:45 INFO - https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:39:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:39:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:39:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:39:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:39:53 INFO - Downloaded 18145016 bytes. 10:39:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:39:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:39:56 INFO - Return code: 0 10:39:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:39:56 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')]} 10:39:56 INFO - https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:39:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:39:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:39:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:39:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:39:59 INFO - Downloaded 18145016 bytes. 10:39:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:39:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:40:02 INFO - Return code: 0 10:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:02 INFO - https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:40:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:40:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:40:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:40:03 INFO - Downloaded 10840152 bytes. 10:40:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:40:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:40:03 INFO - Return code: 0 10:40:03 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')]} 10:40:03 INFO - https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:40:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:40:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:40:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:40:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 10:40:06 INFO - Downloaded 110310332 bytes. 10:40:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:40:06 INFO - mkdir: /builds/slave/test/properties 10:40:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:40:06 INFO - Writing to file /builds/slave/test/properties/build_url 10:40:06 INFO - Contents: 10:40:06 INFO - build_url:https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:40:08 INFO - Running post-action listener: _resource_record_post_action 10:40:08 INFO - Running post-action listener: set_extra_try_arguments 10:40:08 INFO - ##### 10:40:08 INFO - ##### Finished download-and-extract step (success) 10:40:08 INFO - ##### 10:40:08 INFO - ##### 10:40:08 INFO - ##### Running populate-webroot step. 10:40:08 INFO - ##### 10:40:08 INFO - Running pre-action listener: _resource_record_pre_action 10:40:08 INFO - Running main action method: populate_webroot 10:40:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:40:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:40:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:40:08 INFO - '--tppagecycles', 10:40:08 INFO - '7'], 10:40:08 INFO - 'tests': ['tsvgm']}}, 10:40:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:40:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:40:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:40:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:40:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:40:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:40:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['damp', 'tps']}, 10:40:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'tests': ['damp', 'tps']}, 10:40:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['dromaeo_dom']}, 10:40:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:40:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['a11yr', 10:40:08 INFO - 'ts_paint', 10:40:08 INFO - 'tpaint', 10:40:08 INFO - 'sessionrestore', 10:40:08 INFO - 'sessionrestore_no_auto_restore', 10:40:08 INFO - 'tabpaint']}, 10:40:08 INFO - 'other-e10s': {'tests': ['a11yr', 10:40:08 INFO - 'ts_paint', 10:40:08 INFO - 'tpaint', 10:40:08 INFO - 'sessionrestore', 10:40:08 INFO - 'sessionrestore_no_auto_restore', 10:40:08 INFO - 'tabpaint']}, 10:40:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:40:08 INFO - 'ts_paint', 10:40:08 INFO - 'tpaint', 10:40:08 INFO - 'sessionrestore', 10:40:08 INFO - 'sessionrestore_no_auto_restore', 10:40:08 INFO - 'tabpaint']}, 10:40:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:40:08 INFO - 'ts_paint', 10:40:08 INFO - 'tpaint', 10:40:08 INFO - 'sessionrestore', 10:40:08 INFO - 'sessionrestore_no_auto_restore', 10:40:08 INFO - 'tabpaint']}, 10:40:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['a11yr', 10:40:08 INFO - 'ts_paint', 10:40:08 INFO - 'tpaint', 10:40:08 INFO - 'sessionrestore', 10:40:08 INFO - 'sessionrestore_no_auto_restore', 10:40:08 INFO - 'tabpaint']}, 10:40:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['a11yr', 10:40:08 INFO - 'ts_paint', 10:40:08 INFO - 'tpaint', 10:40:08 INFO - 'sessionrestore', 10:40:08 INFO - 'sessionrestore_no_auto_restore', 10:40:08 INFO - 'tabpaint']}, 10:40:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['tsvgx', 10:40:08 INFO - 'tsvgr_opacity', 10:40:08 INFO - 'tart', 10:40:08 INFO - 'tscrollx', 10:40:08 INFO - 'cart']}, 10:40:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:40:08 INFO - 'tsvgr_opacity', 10:40:08 INFO - 'tart', 10:40:08 INFO - 'tscrollx', 10:40:08 INFO - 'cart']}, 10:40:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'talos_options': ['--disable-e10s'], 10:40:08 INFO - 'tests': ['tp5o']}, 10:40:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'tests': ['tp5o']}, 10:40:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'talos_options': ['--disable-e10s', 10:40:08 INFO - '--xperf_path', 10:40:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:40:08 INFO - 'tests': ['tp5n']}, 10:40:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:08 INFO - 'talos_options': ['--xperf_path', 10:40:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:40:08 INFO - 'tests': ['tp5n']}}, 10:40:08 INFO - 'talos.zip': {'path': '', 10:40:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:40:08 INFO - Running post-action listener: _resource_record_post_action 10:40:08 INFO - ##### 10:40:08 INFO - ##### Finished populate-webroot step (success) 10:40:08 INFO - ##### 10:40:08 INFO - ##### 10:40:08 INFO - ##### Running create-virtualenv step. 10:40:08 INFO - ##### 10:40:08 INFO - Running pre-action listener: _resource_record_pre_action 10:40:08 INFO - Running main action method: create_virtualenv 10:40:08 INFO - Creating virtualenv /builds/slave/test/build/venv 10:40:08 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 10:40:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:40:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:40:08 INFO - Using real prefix '/tools/python27' 10:40:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:40:09 INFO - Installing distribute.............................................................................................................................................................................................done. 10:40:12 INFO - Installing pip.................done. 10:40:12 INFO - Return code: 0 10:40:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:40:12 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')]} 10:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:12 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')]} 10:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:12 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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40:12 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 10:40:12 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 10:40:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:12 INFO - 'HOME': '/Users/cltbld', 10:40:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:12 INFO - 'LOGNAME': 'cltbld', 10:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:12 INFO - 'MOZ_NO_REMOTE': '1', 10:40:12 INFO - 'NO_EM_RESTART': '1', 10:40:12 INFO - 'PAGER': '/bin/cat', 10:40:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:12 INFO - 'PWD': '/builds/slave/test', 10:40:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:12 INFO - 'SHELL': '/bin/bash', 10:40:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:12 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:12 INFO - 'USER': 'cltbld', 10:40:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:12 INFO - 'XPC_FLAGS': '0x0', 10:40:12 INFO - 'XPC_SERVICE_NAME': '0', 10:40:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:12 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 10:40:12 INFO - Cleaning up... 10:40:12 INFO - Return code: 0 10:40:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:40:12 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')]} 10:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:12 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')]} 10:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:12 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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40:12 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 10:40:12 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 10:40:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:12 INFO - 'HOME': '/Users/cltbld', 10:40:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:12 INFO - 'LOGNAME': 'cltbld', 10:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:12 INFO - 'MOZ_NO_REMOTE': '1', 10:40:12 INFO - 'NO_EM_RESTART': '1', 10:40:12 INFO - 'PAGER': '/bin/cat', 10:40:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:12 INFO - 'PWD': '/builds/slave/test', 10:40:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:12 INFO - 'SHELL': '/bin/bash', 10:40:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:12 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:12 INFO - 'USER': 'cltbld', 10:40:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:12 INFO - 'XPC_FLAGS': '0x0', 10:40:12 INFO - 'XPC_SERVICE_NAME': '0', 10:40:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:12 INFO - Downloading/unpacking psutil>=0.7.1 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:40:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:40:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:40:15 INFO - Installing collected packages: psutil 10:40:15 INFO - Running setup.py install for psutil 10:40:15 INFO - building 'psutil._psutil_osx' extension 10:40: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 10:40: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 10:40: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 10:40: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 10:40:15 INFO - building 'psutil._psutil_posix' extension 10:40: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 10:40:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:40:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:40:15 INFO - ^ 10:40:15 INFO - 1 warning generated. 10:40:15 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 10:40:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:40:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:40:16 INFO - Successfully installed psutil 10:40:16 INFO - Cleaning up... 10:40:16 INFO - Return code: 0 10:40:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:40: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')]} 10:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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')]} 10:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40: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 10:40: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 10:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:16 INFO - 'HOME': '/Users/cltbld', 10:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:16 INFO - 'LOGNAME': 'cltbld', 10:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:16 INFO - 'MOZ_NO_REMOTE': '1', 10:40:16 INFO - 'NO_EM_RESTART': '1', 10:40:16 INFO - 'PAGER': '/bin/cat', 10:40:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:16 INFO - 'PWD': '/builds/slave/test', 10:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:16 INFO - 'SHELL': '/bin/bash', 10:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:16 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:16 INFO - 'USER': 'cltbld', 10:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:16 INFO - 'XPC_FLAGS': '0x0', 10:40:16 INFO - 'XPC_SERVICE_NAME': '0', 10:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:40: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) 10:40:18 INFO - Installing collected packages: mozsystemmonitor 10:40:18 INFO - Running setup.py install for mozsystemmonitor 10:40:19 INFO - Successfully installed mozsystemmonitor 10:40:19 INFO - Cleaning up... 10:40:19 INFO - Return code: 0 10:40:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:40: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')]} 10:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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')]} 10:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40: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 10:40: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 10:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:19 INFO - 'HOME': '/Users/cltbld', 10:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:19 INFO - 'LOGNAME': 'cltbld', 10:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:19 INFO - 'MOZ_NO_REMOTE': '1', 10:40:19 INFO - 'NO_EM_RESTART': '1', 10:40:19 INFO - 'PAGER': '/bin/cat', 10:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:19 INFO - 'PWD': '/builds/slave/test', 10:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:19 INFO - 'SHELL': '/bin/bash', 10:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:19 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:19 INFO - 'USER': 'cltbld', 10:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:19 INFO - 'XPC_FLAGS': '0x0', 10:40:19 INFO - 'XPC_SERVICE_NAME': '0', 10:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:19 INFO - Downloading/unpacking blobuploader==1.2.4 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:21 INFO - Downloading blobuploader-1.2.4.tar.gz 10:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:40:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:40:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:22 INFO - Downloading docopt-0.6.1.tar.gz 10:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:40:23 INFO - Installing collected packages: blobuploader, requests, docopt 10:40:23 INFO - Running setup.py install for blobuploader 10:40:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:40:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:40:23 INFO - Running setup.py install for requests 10:40:23 INFO - Running setup.py install for docopt 10:40:23 INFO - Successfully installed blobuploader requests docopt 10:40:23 INFO - Cleaning up... 10:40:23 INFO - Return code: 0 10:40:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:40:23 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')]} 10:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:23 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')]} 10:40:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:23 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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40:23 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 10:40:23 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 10:40:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:23 INFO - 'HOME': '/Users/cltbld', 10:40:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:23 INFO - 'LOGNAME': 'cltbld', 10:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:23 INFO - 'MOZ_NO_REMOTE': '1', 10:40:23 INFO - 'NO_EM_RESTART': '1', 10:40:23 INFO - 'PAGER': '/bin/cat', 10:40:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:23 INFO - 'PWD': '/builds/slave/test', 10:40:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:23 INFO - 'SHELL': '/bin/bash', 10:40:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:23 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:23 INFO - 'USER': 'cltbld', 10:40:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:23 INFO - 'XPC_FLAGS': '0x0', 10:40:23 INFO - 'XPC_SERVICE_NAME': '0', 10:40:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-APFewS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-kuOMY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-s9C0QG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-KfP5LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-tQRsUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-fq4Jw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-TysdYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-H1AW9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:40:24 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-zYrqks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-2h8HRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-miTpw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-dUMgoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-hkYze1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-DotQ3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-HOkxdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-jp1rwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:40:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-YFMkj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:40:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:40:25 INFO - Running setup.py install for manifestparser 10:40:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:40:26 INFO - Running setup.py install for mozcrash 10:40:26 INFO - Running setup.py install for mozdebug 10:40:26 INFO - Running setup.py install for mozdevice 10:40:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:40:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:40:26 INFO - Running setup.py install for mozfile 10:40:26 INFO - Running setup.py install for mozhttpd 10:40:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:40:26 INFO - Running setup.py install for mozinfo 10:40:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:40:26 INFO - Running setup.py install for mozInstall 10:40:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Running setup.py install for mozleak 10:40:27 INFO - Running setup.py install for mozlog 10:40:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Running setup.py install for moznetwork 10:40:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Running setup.py install for mozprocess 10:40:27 INFO - Running setup.py install for mozprofile 10:40:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:40:27 INFO - Running setup.py install for mozrunner 10:40:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:40:28 INFO - Running setup.py install for mozscreenshot 10:40:28 INFO - Running setup.py install for moztest 10:40:28 INFO - Running setup.py install for mozversion 10:40:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:40:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:40:28 INFO - Cleaning up... 10:40:28 INFO - Return code: 0 10:40:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:40: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')]} 10:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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')]} 10:40:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40: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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40: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 10:40: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 10:40:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:28 INFO - 'HOME': '/Users/cltbld', 10:40:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:28 INFO - 'LOGNAME': 'cltbld', 10:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:28 INFO - 'MOZ_NO_REMOTE': '1', 10:40:28 INFO - 'NO_EM_RESTART': '1', 10:40:28 INFO - 'PAGER': '/bin/cat', 10:40:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:28 INFO - 'PWD': '/builds/slave/test', 10:40:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:28 INFO - 'SHELL': '/bin/bash', 10:40:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:28 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:28 INFO - 'USER': 'cltbld', 10:40:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:28 INFO - 'XPC_FLAGS': '0x0', 10:40:28 INFO - 'XPC_SERVICE_NAME': '0', 10:40:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:40:28 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Iv0Uah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:40:28 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)) 10:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:40:28 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-rx9EgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:40:28 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)) 10:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:40:28 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-j4HSur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-B0ni9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-YuNeNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-1iKnj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-pOl08M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-3sEygf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-YUtL_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-gNTR6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:40: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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-yxx_o1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:40:29 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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:40:29 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-JtDpxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:40:29 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)) 10:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:40:30 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-_6YeRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:40: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)) 10:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:40:30 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-8RPCaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:40: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)) 10:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:40:30 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-lS8VtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:40: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)) 10:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:40:30 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ej4xxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:40: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)) 10:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:40:30 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-_rl24w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:40: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)) 10:40: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)) 10:40: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)) 10:40: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)) 10:40: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)) 10:40: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)) 10:40:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:32 INFO - Downloading blessings-1.6.tar.gz 10:40:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:40:33 INFO - Installing collected packages: blessings 10:40:33 INFO - Running setup.py install for blessings 10:40:33 INFO - Successfully installed blessings 10:40:33 INFO - Cleaning up... 10:40:33 INFO - Return code: 0 10:40:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:40:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:40:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:40:33 INFO - Reading from file tmpfile_stdout 10:40:33 INFO - Current package versions: 10:40:33 INFO - blessings == 1.6 10:40:33 INFO - blobuploader == 1.2.4 10:40:33 INFO - docopt == 0.6.1 10:40:33 INFO - manifestparser == 1.1 10:40:33 INFO - mozInstall == 1.12 10:40:33 INFO - mozcrash == 0.17 10:40:33 INFO - mozdebug == 0.1 10:40:33 INFO - mozdevice == 0.48 10:40:33 INFO - mozfile == 1.2 10:40:33 INFO - mozhttpd == 0.7 10:40:33 INFO - mozinfo == 0.9 10:40:33 INFO - mozleak == 0.1 10:40:33 INFO - mozlog == 3.1 10:40:33 INFO - moznetwork == 0.27 10:40:33 INFO - mozprocess == 0.22 10:40:33 INFO - mozprofile == 0.28 10:40:33 INFO - mozrunner == 6.11 10:40:33 INFO - mozscreenshot == 0.1 10:40:33 INFO - mozsystemmonitor == 0.0 10:40:33 INFO - moztest == 0.7 10:40:33 INFO - mozversion == 1.4 10:40:33 INFO - psutil == 3.1.1 10:40:33 INFO - requests == 1.2.3 10:40:33 INFO - wsgiref == 0.1.2 10:40:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:33 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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40:33 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 10:40:33 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 10:40:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:33 INFO - 'HOME': '/Users/cltbld', 10:40:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:33 INFO - 'LOGNAME': 'cltbld', 10:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:33 INFO - 'MOZ_NO_REMOTE': '1', 10:40:33 INFO - 'NO_EM_RESTART': '1', 10:40:33 INFO - 'PAGER': '/bin/cat', 10:40:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:33 INFO - 'PWD': '/builds/slave/test', 10:40:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:33 INFO - 'SHELL': '/bin/bash', 10:40:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:33 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:33 INFO - 'USER': 'cltbld', 10:40:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:33 INFO - 'XPC_FLAGS': '0x0', 10:40:33 INFO - 'XPC_SERVICE_NAME': '0', 10:40:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 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)) 10:40:33 INFO - Cleaning up... 10:40:33 INFO - Return code: 0 10:40:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:40:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:40:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:33 INFO - 'HOME': '/Users/cltbld', 10:40:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:33 INFO - 'LOGNAME': 'cltbld', 10:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:33 INFO - 'MOZ_NO_REMOTE': '1', 10:40:33 INFO - 'NO_EM_RESTART': '1', 10:40:33 INFO - 'PAGER': '/bin/cat', 10:40:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:33 INFO - 'PWD': '/builds/slave/test', 10:40:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:33 INFO - 'SHELL': '/bin/bash', 10:40:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:33 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:33 INFO - 'USER': 'cltbld', 10:40:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:33 INFO - 'XPC_FLAGS': '0x0', 10:40:33 INFO - 'XPC_SERVICE_NAME': '0', 10:40:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:34 INFO - Downloading/unpacking jsonschema 10:40:34 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 10:40:34 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 10:40:34 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 10:40:34 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 10:40:34 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 10:40:34 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 10:40:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:40:36 INFO - Downloading/unpacking functools32 (from jsonschema) 10:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:40:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:40:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:40:36 INFO - Downloading functools32-3.2.3-2.tar.gz 10:40:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:40:36 INFO - warning: no files found matching '*.txt' 10:40:36 INFO - no previously-included directories found matching 'build' 10:40:36 INFO - no previously-included directories found matching 'dist' 10:40:36 INFO - no previously-included directories found matching '.git*' 10:40:37 INFO - Installing collected packages: jsonschema, functools32 10:40:37 INFO - Running setup.py install for functools32 10:40:37 INFO - warning: no files found matching '*.txt' 10:40:37 INFO - no previously-included directories found matching 'build' 10:40:37 INFO - no previously-included directories found matching 'dist' 10:40:37 INFO - no previously-included directories found matching '.git*' 10:40:37 INFO - Successfully installed jsonschema functools32 10:40:37 INFO - Cleaning up... 10:40:37 INFO - Return code: 0 10:40:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:40:37 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 0x10d7faa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d800570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb08bc6d730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, '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.WsWaFfHXGG/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.9SomJDmK0N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 10:40:37 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 10:40:37 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 10:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:40:37 INFO - 'HOME': '/Users/cltbld', 10:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:40:37 INFO - 'LOGNAME': 'cltbld', 10:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:37 INFO - 'MOZ_NO_REMOTE': '1', 10:40:37 INFO - 'NO_EM_RESTART': '1', 10:40:37 INFO - 'PAGER': '/bin/cat', 10:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:37 INFO - 'PWD': '/builds/slave/test', 10:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:40:37 INFO - 'SHELL': '/bin/bash', 10:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:40:37 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:40:37 INFO - 'USER': 'cltbld', 10:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:37 INFO - 'XPC_FLAGS': '0x0', 10:40:37 INFO - 'XPC_SERVICE_NAME': '0', 10:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:40:37 INFO - Downloading/unpacking flake8 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40: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 10:40:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:40:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:40:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:40:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:40:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:40:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:40:40 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 10:40:40 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 10:40:40 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 10:40:40 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 10:40:40 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 10:40:40 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 10:40:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:40:40 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 10:40:40 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 10:40:40 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 10:40:40 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 10:40:40 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 10:40:40 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 10:40:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:40:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:40:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:40:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:40:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:40:41 INFO - no previously-included directories found matching 'docs/_build' 10:40:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:40:41 INFO - Running setup.py install for pep8 10:40:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:40:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:40:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:40:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:40:41 INFO - no previously-included directories found matching 'docs/_build' 10:40:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:40:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:40:41 INFO - Cleaning up... 10:40:41 INFO - Return code: 0 10:40:41 INFO - Running post-action listener: _resource_record_post_action 10:40:41 INFO - Running post-action listener: _start_resource_monitoring 10:40:41 INFO - Starting resource monitoring. 10:40:41 INFO - ##### 10:40:41 INFO - ##### Finished create-virtualenv step (success) 10:40:41 INFO - ##### 10:40:41 INFO - ##### 10:40:41 INFO - ##### Running install step. 10:40:41 INFO - ##### 10:40:41 INFO - Running pre-action listener: _resource_record_pre_action 10:40:41 INFO - Running main action method: install 10:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:40:41 INFO - Reading from file tmpfile_stdout 10:40:41 INFO - Detecting whether we're running mozinstall >=1.0... 10:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:40:41 INFO - Reading from file tmpfile_stdout 10:40:41 INFO - Output received: 10:40:41 INFO - Usage: mozinstall [options] installer 10:40:41 INFO - Options: 10:40:41 INFO - -h, --help show this help message and exit 10:40:41 INFO - -d DEST, --destination=DEST 10:40:41 INFO - Directory to install application into. [default: 10:40:41 INFO - "/builds/slave/test"] 10:40:41 INFO - --app=APP Application being installed. [default: firefox] 10:40:41 INFO - mkdir: /builds/slave/test/build/application 10:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:41:15 INFO - Reading from file tmpfile_stdout 10:41:15 INFO - Output received: 10:41:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:41:15 INFO - Running post-action listener: _resource_record_post_action 10:41:15 INFO - ##### 10:41:15 INFO - ##### Finished install step (success) 10:41:15 INFO - ##### 10:41:15 INFO - ##### 10:41:15 INFO - ##### Running run-tests step. 10:41:15 INFO - ##### 10:41:15 INFO - Running pre-action listener: _resource_record_pre_action 10:41:15 INFO - Running main action method: run_tests 10:41:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:41:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:41:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:41:15 INFO - 2016-05-07 10:41:15.308 screenresolution[1941:15537] starting screenresolution argv=screenresolution get 10:41:15 INFO - 2016-05-07 10:41:15.311 screenresolution[1941:15537] Display 0: 1600x1200x32@50 10:41:15 INFO - 2016-05-07 10:41:15.317 screenresolution[1942:15539] starting screenresolution argv=screenresolution list 10:41:15 INFO - Available Modes on Display 0 10:41:15 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 10:41:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:41:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:41:15 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 10:41:15 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 10:41:15 INFO - Graphics/Displays: 10:41:15 INFO - Intel Iris: 10:41:15 INFO - Chipset Model: Intel Iris 10:41:15 INFO - Type: GPU 10:41:15 INFO - Bus: Built-In 10:41:15 INFO - VRAM (Dynamic, Max): 1536 MB 10:41:15 INFO - Vendor: Intel (0x8086) 10:41:15 INFO - Device ID: 0x0a2e 10:41:15 INFO - Revision ID: 0x0009 10:41:15 INFO - Displays: 10:41:15 INFO - Display: 10:41:15 INFO - Resolution: 1600 x 1200 @ 50 Hz 10:41:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:41:15 INFO - Display Serial Number: 1600x1200 50 10:41:15 INFO - Main Display: Yes 10:41:15 INFO - Mirror: Off 10:41:15 INFO - Online: Yes 10:41:15 INFO - Rotation: Supported 10:41:15 INFO - Return code: 0 10:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:41:15 INFO - Python 2.7.3 10:41:15 INFO - Return code: 0 10:41:15 INFO - grabbing minidump binary from tooltool 10:41:15 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')]} 10:41:15 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 0x10d69ed30>, '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 0x10d4c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d695b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:41:15 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 10:41:15 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 10:41:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:41:15 INFO - Return code: 0 10:41:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:41:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:41:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:41:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:41:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:41:16 INFO - Return code: 0 10:41:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WsWaFfHXGG/Render', 10:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:16 INFO - 'HOME': '/Users/cltbld', 10:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:16 INFO - 'LOGNAME': 'cltbld', 10:41:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:41:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:16 INFO - 'MOZ_NO_REMOTE': '1', 10:41:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:41:16 INFO - 'NO_EM_RESTART': '1', 10:41:16 INFO - 'PAGER': '/bin/cat', 10:41:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:16 INFO - 'PWD': '/builds/slave/test', 10:41:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:16 INFO - 'SHELL': '/bin/bash', 10:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SomJDmK0N/Listeners', 10:41:16 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 10:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:16 INFO - 'USER': 'cltbld', 10:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:16 INFO - 'XPC_FLAGS': '0x0', 10:41:16 INFO - 'XPC_SERVICE_NAME': '0', 10:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:41:16 INFO - mozversion application_buildid: 20160507093556 10:41:16 INFO - mozversion application_changeset: 52749e84bf25fb6aed91ab1339b74cc0e7aae5e7 10:41:16 INFO - mozversion application_display_name: Nightly 10:41:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:41:16 INFO - mozversion application_name: Firefox 10:41:16 INFO - mozversion application_remotingname: firefox 10:41:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:41:16 INFO - mozversion application_vendor: Mozilla 10:41:16 INFO - mozversion application_version: 49.0a1 10:41:16 INFO - mozversion platform_buildid: 20160507093556 10:41:16 INFO - mozversion platform_changeset: 52749e84bf25fb6aed91ab1339b74cc0e7aae5e7 10:41:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:41:16 INFO - mozversion platform_version: 49.0a1 10:41:16 INFO - using testdate: 1462642876 10:41:16 INFO - actual date: 1462642876 10:41:16 INFO - starting webserver on 'localhost:50280' 10:41:16 INFO - SUITE-START | Running 2 tests 10:41:16 INFO - TEST-START | dromaeo_css 10:41:16 INFO - operating with platform_type : mac_ 10:41:16 INFO - Initialising browser for dromaeo_css test... 10:41:16 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpN37M8t/profile http://localhost:50280/getInfo.html) 10:41:19 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 10:41:19 INFO - PROCESS | 1960 | colorDepth:24 10:41:19 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 10:41:19 INFO - PROCESS | 1960 | __metrics 10:41:19 INFO - PROCESS | 1960 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:41:19 ERROR - PROCESS | 1960 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:41:19 INFO - TEST-INFO | 1960: exit 0 10:41:19 INFO - Browser initialized. 10:41:19 INFO - Running cycle 1/1 for dromaeo_css test... 10:41:19 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpN37M8t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:41:31 INFO - PROCESS | 1962 | RSS: Main: 238944256 10:41:31 INFO - PROCESS | 1962 | 10:42:23 INFO - PROCESS | 1962 | 1462642943438 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] 10:44:34 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50280/tests/dromaeo/cssquery-ext.html) 10:44:35 INFO - PROCESS | 1962 | RSS: Main: 357416960 10:44:35 INFO - PROCESS | 1962 | 10:47:38 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50280/tests/dromaeo/cssquery-ext.html (next: http://localhost:50280/tests/dromaeo/cssquery-jquery.html) 10:47:39 INFO - PROCESS | 1962 | RSS: Main: 374345728 10:47:39 INFO - PROCESS | 1962 | 10:50:41 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50280/tests/dromaeo/cssquery-mootools.html) 10:50:42 INFO - PROCESS | 1962 | RSS: Main: 369389568 10:50:42 INFO - PROCESS | 1962 | 10:53:44 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50280/tests/dromaeo/cssquery-prototype.html) 10:53:45 INFO - PROCESS | 1962 | RSS: Main: 367165440 10:53:45 INFO - PROCESS | 1962 | 10:56:48 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50280/tests/dromaeo/cssquery-yui.html) 10:56:49 INFO - PROCESS | 1962 | RSS: Main: 331239424 10:56:49 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50280/tests/dromaeo/cssquery-yui.html (next: http://localhost:50280/tests/dromaeo/cssquery-dojo.html) 10:59:52 INFO - PROCESS | 1962 | RSS: Main: 376864768 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | __start_tp_report 10:59:52 INFO - PROCESS | 1962 | _x_x_mozilla_page_load 10:59:52 INFO - PROCESS | 1962 | _x_x_mozilla_page_load_details 10:59:52 INFO - PROCESS | 1962 | |i|pagename|runs| 10:59:52 INFO - PROCESS | 1962 | |0;dojo.html;3223.693749093836;3439.86240550378;3471.9132021699456;3477.4436090225536;3494.4932984717216;5662.688552129631;5735.455131762481;5743.798967036155;5822.155787410828;5863.706814659266;5758.798442054529;5837.854053648651;5852.912206316909;5860.062390017598;5876.647401155923;5249.527542521172;5270.578353731692;5326.334208223972;5336.706481143536;5369.40936496987;5346.251524786514;5353.732313384335;5369;5384.757685904144;5389.892202155955;5259.842204733845;5268.420473747885;5292.894142117156;5294.894102117955;5361.544268737162;5366.06093933564;5372.409035006146;5380.408155102936;5381.031414345416;5401.6218864679095;5112.718800465973;5225.634205605609;5242.475752424765;5287.682739035632;5288.65623734456;5660.490555849993;5690.60165788395;5691.288588926384;5716.799912003102;5720;6240.31356550779;6244.656543890084;6258.405451482147;6271.498280137578;6280.905786413207;5713.885722285572;5958.553108516835;6207.286162091372;6233.625982441023;6242.937570624281;5918.763249470059;5951.226340575698;5967.075103358986;5968.283805943272;5973.761049557969;6288.5598008139905;6291.339409362043;6302.779402720883;6307.148534947723;6310.432061114522;5311.920321195185;6057.727402266909;6066.575339726177;6144.846378840565;6242.375762423721;6275.090111933835;6292.968535157386;6299.464545513682;6301;6301.810945671546;6204.410580994799;6275.058741188767;6281;6285.214348206474;6312.652804095727;6104.883608894094;6180.381961803873;6215.254169499599;6276.780312689034;6297.338779428185;4877.68295060827;4889.193283108319;4904.509549045067;4916.410030796327;4931.90136197274;4412.271975124068;4416.098614234671;4419.027813881005;4438.090191510732;4475.060237350128;5703.771849125988;5858.970705146532;5864.06175011996;5876.324222714357;5904.940950590525;6146.58628515444;6303;6305.369463053749;6311.558190926683;6325.209348831396;5809.244798176294;5821.621594596338;5876.265466816648;5893.469587737124;5895.55783316253;5427.6200665953;5439.5648348132845;5445.37378201504;5450.073487507133;5450.809221677222;6222.284437289589;6282.811515654537;6371.7132729028235;6373.71318290678;6412.102305677209;6260.780872669435;6305.558610897377;6307.779227727007;6316.747330106744;6356.936430635635;6343.809685709436;6352.777652782131;6364.109024736633;6377.840553986188;6387.712552935222;6098.886104840251;6142;6182.969085154546;6216.53375996793;6231.5637905348;5412.972935135299;5657.575681823962;6242.126102345766;6259.874802503833;6311.400416960381;6194.318624951162;6209.2548894133;6213.471854892385;6234.345393733593;6236.75052997875;5937.584369094122;5945.672987985702;6151.630902145886;6152.477039451696;6160.137580738789;5769.567282453749;5915.40845915405;5974.2830860297045;5974.462298393166;6053.243344581927;5535.972320138373;6114.174586430775;6138.815835524942;6163.291221509584;6177.660228687465;5854.063349864001;5864.384239654775;5986.102084687332;6027.156198132177;6054 10:59:52 INFO - PROCESS | 1962 | |1;ext.html;490.1446975028576;493.0976313346576;498.76059491444107;502.5954106943911;520.9034981364229;31769.36461270776;33433.49849752256;45272.86817829556;47361.52638473623;47446.76276618612;44880.3267950981;45239.321410178876;45257.77371113148;45359.464053594624;45364;9721.465319407413;9886.110250077492;9887;9909.454979976099;9913.306068575203;8688.044315125331;8897.599608017623;11597.47811348487;11855.229410088317;11873.109516786275;11730.472128754183;11799.115066370014;11822.527098916076;11828.645140645795;11834.230774999598;8756.649734010633;11591.768164636744;11661.24201926872;11734.647960561197;11736.823947640793;11458.598949036827;11640.592579259684;11644.767104657943;11658.183927125105;11660.242084264495;41501.79249103765;41907.580924190675;41951.370729439084;42022.78988605068;42146.789266053784;3274.983625081883;3290.2596915694016;3385.9830700846583;3530.4174811156126;3623.873164439232;29670;29741.85129074341;29840.701592984227;30092.398152037054;30518.084457466313;2559.539282929072;2758.848263345535;2761.240658818821;2789.4560560690675;2794.7624451921406;508623;512759.43620282406;513168.43415783055;513257;513484;35874;36715.449268261;67568;68227.65886170538;68264.6586767063;37646.811765941;38034.809825950695;38075;38106.42840357397;38115.23769524649;15340.079595224322;15452.459163929208;15495.535133946;15505.689886202439;15525.611859703371;11721.882781172078;11917;12749.808752868528;12880.613581592566;12990.545330913561;8194.262516373556;8243.793905152419;8263.876041859377;8346.04020537646;8351.039630442456;3378.44255697812;3400.6429324921023;3403.217260030207;3409.7783644063056;3429.794212347217;956.8469044952914;1033.1682995188794;1039.887320567001;1093.814051611228;1134.6993046842592;29100;29494.705052949197;29871.402571948438;30109.849450752605;30166.84916575403;1092.8252128961399;1104.4919337104861;1145.8682251541013;1160.8606967163994;1188.1504724122121;15379.384824607114;15561.610959725871;15565.455209067855;15769;15870.60323491922;659931;663701;665232;670064;670884;58225.417745822;63109.68445157745;63189;63225;63242.36757632457;1295.4947570447532;1297.7339645372676;1331.8601546837635;1440.1575078579149;1472.249152931991;29751.55372669455;30425.23936901595;30476.390472190877;30913.51753995613;31386.05841824749;48224;52519;53169.734151330544;53661.731691341294;54047.72976135094;505535;506357;506789;507180;507916;23966.52066958686;26958.730412696408;27534.311642209104;27830.16509504718;28658;46038;46124.07751844877;46465.07069858652;46926.53073469358;47744.2838357418;35961;38102.80948595239;38134.046648834;38845.640402586905;39147.41278880876;36028.45957310695;37622.8118859404;37989.430158548195;38502.61497385102;38756.41865372078;37486.43770343393;37747.43378849264;38437.23125537418;38470.8458746238;38495.615043849204;27263;33461.83269083639;33596.83201583976;33618.49572256467;33630.4598879158;21275.344111591832;21939.92048445719;24278.87860560686;25268;25674.8716256425 10:59:52 INFO - PROCESS | 1962 | |2;jquery.html;353.7576759919455;373.79814899954016;393.2606699405118;408.42616124345267;417.2302102620666;19355.612887742252;21054.57890842186;21930.671039934412;21941.24470042397;21984.89007554964;20285.59428811423;21202;21319;21374.679379809317;21728.13087476499;5034.949650503503;5456.7544460499375;5471.452854714516;5472.5621950243885;5477.863053423676;3843.1737176507045;4040.0101975016164;5346.572274218072;5682.403347648499;6006.969965150168;5251;6024.21685180928;6030.638161710312;6033.7888173913925;6051.818445446644;5736.856578585527;5974.6713930733795;5977.342492325863;5983.491403230731;5984.521238300926;3727.1986522897437;3957.663598594123;3958.604139586047;5446.809361672362;5735.369109397963;11796.29222246668;11928.343941083236;12025.819612705816;13736.587902362946;13785;2112.5141217519904;2148.1729534129317;2164.404788683125;2400.663907053014;2495.887685054177;9893.406395616214;9969.65106221286;10184.94907525465;12892.400683107035;14459.638509037359;1676.2791999440235;1732.7747392838976;1733.3586572968084;1782.97325540117;1795.0755360988971;80391.39217215624;99476;131543;132282;133702;11392.943035284936;11709.355985420883;11767.588134415255;11857.822132668018;12175.269483830998;15211.239438028055;15266.236688165547;15284.38862445512;15295.617609559627;15310.779936909086;8026;8516.032439176544;8757.737267881974;8838.07200243965;8872.157145071207;8630.870536941951;8661.30709543235;8683;8757.518336491554;8772.736817895473;3889.8444062237195;4620.815167393266;4627.311031473488;4635.791389387486;4690.530946905282;1700.4218565687725;1742.8257174282471;1790.8449050362444;1889.3198448558508;1921.5100149461798;858.3176374782063;877.6884206106868;888.9333300002526;917.5824999625288;975.4293738163254;19763.110660020335;20142.39572812818;20194.192232310837;20449.693254601192;20586.382408527767;902.2240872849344;902.2376092202079;962.9037096290455;986.0090608937971;1021.5198856537415;10005;10857.239993200557;10892.509837057352;10895.727606809893;10909.890901090888;593236;595845;599664;609039;610341;121682.39158804326;122667;124706;125201.37399312945;129079;906.9727908162633;1008.8940661230612;1017.3285631483334;1044.3159730376615;1044.984325235122;14622.195779232245;18507;18600.86090099656;19133.234670613576;19172;51091.744541277054;52272.954540909726;52626.42120736384;53496.66258343417;53794.193087104504;75884.62057689676;82963;83001;85083.57458212669;87204.69192961983;7459.664315105942;7915;8060.556669383241;8110.472819266729;8130.471519351223;9036.322275829207;11722.706932326419;12362.567310144103;12410.503579856704;12542.874571254171;11027.558897644325;11112.166587506132;11118.944405277922;11126.165537584875;11479.885201147881;9368.906310936803;10804.189685773441;11044.558217671203;11190.66428007161;11291.548338066385;5774.624649397776;8978.371513993958;10939.3044078168;11223.55105795759;11613.825792612948;7775.028121484815;8056.274935255855;8115.462437828386;8133.292008678244;8352.958235208785;8010.799730006796;9967.700968970943;11059.50232239535;11387.487563059516;11392.202545821712 10:59:52 INFO - PROCESS | 1962 | |3;mootools.html;1389.7776355783076;1634.7466142747874;1663.883528153029;1881.6895212289974;1986.3544348086866;7724.729634462795;7826.8043298917555;8580.356473264499;8902;8980.281577473803;8577.399582029246;8829.984551776548;8831.02858685544;8833.381663283573;8841.425307355017;2042.5200077981663;2108.746950365958;2403.831731778776;2439.634054891763;2486.415692312305;2478.9008439662393;2507.210228777938;2519.004993027753;2523.1799665108915;2525.090967251797;2431.9513609727796;2446.3639453742067;2477.8884950177285;2479.6528486011885;2483.3170878008514;2150.4086376246655;2235.418791114322;2237.518933429304;2420.4070002849326;2472.2830379189922;2336.5093330400637;2421.61254199329;2423.2245681381964;2424.5393375258823;2425.4906469641396;8706.869396959051;8767.78080547985;8782.780430489289;8786.341024423129;8792.824143517159;5211.140161873286;5385.165299378603;5394.109971854639;5421.891562168774;5425.538829199523;3012.9246768830953;3037.3925214956876;3457.1184347991352;3616.7106631469683;3638.6361363863666;1002.2633364477103;1052.1950707708609;1053.3206082077018;1055.7413433708714;1062.3466568060664;11517.36654484003;11521.942390288079;11524.078073754163;11531.019863311629;11560.595379161774;4771.832985845548;4810.230363141881;4893.486183950634;4902.191138462185;4937.876553086202;9855.605775769032;9871.062249086326;9878.654247101316;9889.505524723734;9908.554115064839;5408.026555220098;5421.26812880264;5448.700321482356;5466.1527463243265;5478.986387518322;4445.711028783118;4525.977370113128;4780.139574876486;4890.58430033444;4919.434265059493;3484.9477257841154;3969.960300397017;3992.201559688074;4089.0799570097083;4121.608447197512;1123.455124264731;1294.1264646363668;1354.7222819322014;1365.0990346371545;1378.4968486502555;910.4582773249906;928.2017464980112;930.6184464369576;950.5959967013991;980.0444566547701;5880.470757631833;5919.230500035054;6001.309849367379;6143.539234557426;6235.251769787564;962.6726912849665;979.9955046077828;1003.2826529031777;1003.4380746781826;1003.934744241622;3895;3968.1270120573427;4000.3399439092223;4038.273110840018;4066.6949978751713;8901.73294801157;11035.668929932115;11037.9513946175;11043;11047.83428248577;10061.79876402468;10900.890991089987;10937.890621093846;10963.56145754177;10969.780604388026;8228.917710822934;8305;8308.086110527836;8390.622421991147;8394.8321033579;5625.46558076982;5820.185174075717;5906.468417842415;5987.970060149672;6117.510599152146;9695.854562181432;9736.22110231194;9804.313698041069;10095.798084038424;10226.926172751753;10123.291369604352;10190.898091019291;10286.177105831664;10316.793664126526;10330.586776529155;6351.745930162741;6480.57876238043;6624.924660161267;7026.332498412847;7166.605836679033;7903.802404939923;8557.015943166616;8557.229849313591;8563.82872342562;8586.141385861363;6506.4144227017905;7492.438067145095;7519.774406767806;7531.510451820834;7704.499207551491;7389.445791565547;7446.590437525988;7517.436192285491;7525.924740752523;7525.924740752742;6924.757633482804;7079.575225486487;7089.468289878383;7096.112985876765;7106.253843346374;4451.1765323415275;4923.507649235048;4955.776990035385;5028.572571331478;5179.870503237449;4956.677815941952;4992.6255530834605;5012.824551140692;5040.193569028937;5231.189165679326 10:59:52 INFO - PROCESS | 1962 | |4;prototype.html;310.709486630001;311.7793837127645;316.74660271782585;321.7892280556236;323.3145731050174;5061.443241243465;5147.433782283946;5179.766910489027;5187.22191671249;5227.581793456524;4351.760653164075;4461.442319710036;4671.925457144859;5057.797688092481;5195.532402083812;3494.912627184315;3499.1077275294765;3509.298140371923;3796.335641262782;3834.332065551494;3301.6038075430984;3741.9896627910584;3867.7679339239735;3937.7243592948507;3944.895429279792;3618.3848745713162;3898.629630185128;3912.471816304791;3937.763734175944;3975.6819454443576;3716.2195938852874;3741.9064523386996;3784.6972242220554;3929.783861887595;3968.940465893014;3208.470602350609;3452.188735647121;3474.1314671332166;3477.2523907360037;3487.5814902211896;4009.4386785850006;4207.347861081537;4778.498257682928;4934.1611925972675;4972.90054198914;4247.7451352918615;4480.215962206634;4490.854832017847;4542.795574199169;4559.430071241061;4744.976275118603;4983.825566105169;5269.578433725293;5329.680219186823;5334;3304.339132173575;3353.96646033539;3687.690869741257;3769.963260103466;3796.2217745362136;4124.381342798544;5292.841214763564;5597.832065038056;5714.028705073339;5735.999999999958;3476.634953329864;3734.2158146788934;3739.439084137401;3754.3617585010616;3773.9621604058552;3316.7512436567354;3695.667389934919;3809.6380244248744;3820.2741479118877;3824.4836947012354;4417.425734654538;4436.866893993185;4438.356438316426;4452.933206001912;4486.775661216926;4578.153041687232;4660.953390466119;4998.325226094431;5333.4931982474745;5471.8084867029465;1149.057772626455;1166.5333866453486;1278.520554791953;1283.5250957145915;1305.2690493323769;809.336344197764;848.6605357856783;853.9283199584606;911.5988964855443;912.5756523216736;4268.487781466182;5045.8738531537;5060.671056381323;5085.618578606619;5097.745112744348;4898.044881248194;5021.472745361758;5553.416891226363;5586.301712285965;5600.35595906476;4422.380866678667;5059.797608095709;5068.341115654941;5106.336176297058;5229.450907654717;2368.4539120445247;2474.241221038565;2658.142496815931;2825.671934190927;2833.2916770807296;5428.647137936022;5551;5581.190727344512;5655.3779084300695;5679.716014199356;4760.809567617256;4833.154198015333;5370.8657283567445;5463.808766693147;5469.9214298567085;5791.247137872049;5811.622244554091;5812.505936995402;5836.768342951326;5850.590458667937;5461.890762184814;5473.452654734495;5587.636803607753;5647.689377084135;5676.68778217202;4209.326507758744;5114.411842638145;5255.2379904912805;5606.495415412469;5694.259746232963;5234.685918844882;5719.370869204467;5806.390329015392;5858.041688947054;5896.233489646319;3763.8494460221286;3818.6181381861593;3824.4263360495033;3884.8057597120596;3884.9223015538964;4344.782760862007;5467.906418716193;5680.233168522197;5705.771769129188;5753.942460575341;4278.401023856725;5526.281583394133;5537.501624853621;5573.665580065209;5603.663780173202;4750.9287360690305;5101.821436249714;5179.1195496764885;5434.1033729435785;5545.724483368767;4293.227219100592;4696.765161741968;4755.025219829996;4805.903881922272;5345.54562862161;4589.518100599389;4909.3126962224605;5041.546260836542;5242.749495312931;5436.891262174813;4447.866564003085;5343.278657381204;5363.980843639696;5398.109311963639;5408.188771684278 10:59:52 INFO - PROCESS | 1962 | |5;yui.html;677.86103848711;704.4505285877015;716.1585137463479;740.171008470513;746.5557992994167;16650.83349166508;17177.484675459757;17238;17585.912070439663;17603.647927041453;15073.547793566211;16248.268827902773;16256.75614865778;16263.51209463715;16267.755983660225;720.765751130882;749.6027105634023;927.1562877781238;931.6599441203946;933.2160984772789;893.9731808045769;930.1907340613676;935.9438433693966;937.4843835890233;940.2384068904175;967.7387105481516;973.1922504321396;979.2704435195789;980.2158273381302;980.5391466010964;742.4580056558742;924.191332583989;971.6696323250059;971.7522031881896;972.1493693018608;721.9061522002158;725.365305357812;726.1032624708481;726.513236131793;727.1383410658386;15003.849961500355;15103.848961510354;15111.31999060045;15114.470993515284;15146;12059.517619295219;12176.878231217752;12257.693557661041;12406.317652529107;12487.31319777412;10780.353178809297;10939.890601094046;11058.778824423547;11358;11643.708907277301;4013.538443079054;4029.133736246721;4068.288049591339;4142.130152667944;4163.91672166555;82037.17962820355;82637.9340516492;82869;83371.5831420839;83607.58196209041;8078.35373170145;9391.248700103975;9421.764455888657;9535.618575256913;9608.711738647851;20746.585068298853;20786.58426831485;20897.79102208989;20921.163153473994;21155.8942205288;7400.074990626172;7443.367313778282;7445.181030086686;7552.246214941673;7742.341900938371;4044.474218351654;4108.383742438658;4108.794560271975;4109.219248342805;4148.62662360389;3462.3767721809813;3559.911002224965;3562.109472631842;3563.6436356364156;3572.4105522588484;640.2956747577628;650.3074225949443;660.5112216959511;662.5064327076359;665.6405541007898;484.5033840313636;485.78139837073456;528.9285946397264;561.4666067236142;567;7866.7639970800965;7873.921260787319;7908.565028923349;8017.398695097774;8076.3942704297415;507.47983317100267;510.67827268820406;573.8651416917021;583.215575051555;583.6177303865975;3574.1779390739757;3982.900427489336;4138.8551400700835;4298.591633794785;4390.495093064274;61416;67698.32301677018;69637;69637.65181174062;69734.60530789457;71220.93168602475;71366;73529.63235183789;73645.26354736384;79850.402991941;60670.449716271454;61851;62706;74095.62952185313;74717.62641186759;8255.917440825755;8340.41617086798;8348.916510835057;8450.95774521148;8572.65709371643;40064.99837504086;40947.7952610235;41121.38317925174;42727.5727242736;43806;45140.614453915434;51047;57012.71493642505;57113.71443142758;57182;10973.341599504056;11067.335959842436;11122.61070862516;11157;11302.660920172408;11845.289282643069;11926.761464770829;12235.93882030584;12323;12764.29796361209;9073.500957447404;10850.457477125954;10914.345139291669;11407.372594507382;11699.88300116988;9514.905785834717;11129.387883666475;11155.330680158891;11177.776444471228;11640.126990475917;10892.292001019909;11058.557657693602;11077.723056923642;11089.33463992163;11258.324500529994;9268.443893366419;9409.106134917172;9417.199538039069;9591.740030426328;9723.558591120802;9322.254219662274;9421.557632398753;9426.198773104363;9511.952440237754;9699.466529340954 10:59:52 INFO - PROCESS | 1962 | __end_tp_report 10:59:52 INFO - PROCESS | 1962 | __start_cc_report 10:59:52 INFO - PROCESS | 1962 | _x_x_mozilla_cycle_collect,1175 10:59:52 INFO - PROCESS | 1962 | __end_cc_report 10:59:52 INFO - PROCESS | 1962 | __startTimestamp1462643992615__endTimestamp 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | ------- Summary: start ------- 10:59:52 INFO - PROCESS | 1962 | Number of tests: 6 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | [#0] dojo.html Cycles:165 Average:5780.68 Median:5951.23 stddev:627.23 (10.5%) stddev-sans-first:596.22 10:59:52 INFO - PROCESS | 1962 | Values: 3223.7 3439.9 3471.9 3477.4 3494.5 5662.7 5735.5 5743.8 5822.2 5863.7 5758.8 5837.9 5852.9 5860.1 5876.6 5249.5 5270.6 5326.3 5336.7 5369.4 5346.3 5353.7 5369.0 5384.8 5389.9 5259.8 5268.4 5292.9 5294.9 5361.5 5366.1 5372.4 5380.4 5381.0 5401.6 5112.7 5225.6 5242.5 5287.7 5288.7 5660.5 5690.6 5691.3 5716.8 5720.0 6240.3 6244.7 6258.4 6271.5 6280.9 5713.9 5958.6 6207.3 6233.6 6242.9 5918.8 5951.2 5967.1 5968.3 5973.8 6288.6 6291.3 6302.8 6307.1 6310.4 5311.9 6057.7 6066.6 6144.8 6242.4 6275.1 6293.0 6299.5 6301.0 6301.8 6204.4 6275.1 6281.0 6285.2 6312.7 6104.9 6180.4 6215.3 6276.8 6297.3 4877.7 4889.2 4904.5 4916.4 4931.9 4412.3 4416.1 4419.0 4438.1 4475.1 5703.8 5859.0 5864.1 5876.3 5904.9 6146.6 6303.0 6305.4 6311.6 6325.2 5809.2 5821.6 5876.3 5893.5 5895.6 5427.6 5439.6 5445.4 5450.1 5450.8 6222.3 6282.8 6371.7 6373.7 6412.1 6260.8 6305.6 6307.8 6316.7 6356.9 6343.8 6352.8 6364.1 6377.8 6387.7 6098.9 6142.0 6183.0 6216.5 6231.6 5413.0 5657.6 6242.1 6259.9 6311.4 6194.3 6209.3 6213.5 6234.3 6236.8 5937.6 5945.7 6151.6 6152.5 6160.1 5769.6 5915.4 5974.3 5974.5 6053.2 5536.0 6114.2 6138.8 6163.3 6177.7 5854.1 5864.4 5986.1 6027.2 6054.0 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | [#1] ext.html Cycles:180 Average:68758.75 Median:3480.11 stddev:151561.67 (4355.1%) stddev-sans-first:151900.17 10:59:52 INFO - PROCESS | 1962 | Values: 490.1 493.1 498.8 502.6 520.9 31769.4 33433.5 45272.9 47361.5 47446.8 44880.3 45239.3 45257.8 45359.5 45364.0 9721.5 9886.1 9887.0 9909.5 9913.3 8688.0 8897.6 11597.5 11855.2 11873.1 11730.5 11799.1 11822.5 11828.6 11834.2 8756.6 11591.8 11661.2 11734.6 11736.8 11458.6 11640.6 11644.8 11658.2 11660.2 41501.8 41907.6 41951.4 42022.8 42146.8 3275.0 3290.3 3386.0 3530.4 3623.9 29670.0 29741.9 29840.7 30092.4 30518.1 2559.5 2758.8 2761.2 2789.5 2794.8 508623.0 512759.4 513168.4 513257.0 513484.0 35874.0 36715.4 67568.0 68227.7 68264.7 37646.8 38034.8 38075.0 38106.4 38115.2 15340.1 15452.5 15495.5 15505.7 15525.6 11721.9 11917.0 12749.8 12880.6 12990.5 8194.3 8243.8 8263.9 8346.0 8351.0 3378.4 3400.6 3403.2 3409.8 3429.8 956.8 1033.2 1039.9 1093.8 1134.7 29100.0 29494.7 29871.4 30109.8 30166.8 1092.8 1104.5 1145.9 1160.9 1188.2 15379.4 15561.6 15565.5 15769.0 15870.6 659931.0 663701.0 665232.0 670064.0 670884.0 58225.4 63109.7 63189.0 63225.0 63242.4 1295.5 1297.7 1331.9 1440.2 1472.2 29751.6 30425.2 30476.4 30913.5 31386.1 48224.0 52519.0 53169.7 53661.7 54047.7 505535.0 506357.0 506789.0 507180.0 507916.0 23966.5 26958.7 27534.3 27830.2 28658.0 46038.0 46124.1 46465.1 46926.5 47744.3 35961.0 38102.8 38134.0 38845.6 39147.4 36028.5 37622.8 37989.4 38502.6 38756.4 37486.4 37747.4 38437.2 38470.8 38495.6 27263.0 33461.8 33596.8 33618.5 33630.5 21275.3 21939.9 24278.9 25268.0 25674.9 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | [#2] jquery.html Cycles:180 Average:34640.31 Median:2040.48 stddev:100455.92 (4923.2%) stddev-sans-first:100704.74 10:59:52 INFO - PROCESS | 1962 | Values: 353.8 373.8 393.3 408.4 417.2 19355.6 21054.6 21930.7 21941.2 21984.9 20285.6 21202.0 21319.0 21374.7 21728.1 5034.9 5456.8 5471.5 5472.6 5477.9 3843.2 4040.0 5346.6 5682.4 6007.0 5251.0 6024.2 6030.6 6033.8 6051.8 5736.9 5974.7 5977.3 5983.5 5984.5 3727.2 3957.7 3958.6 5446.8 5735.4 11796.3 11928.3 12025.8 13736.6 13785.0 2112.5 2148.2 2164.4 2400.7 2495.9 9893.4 9969.7 10184.9 12892.4 14459.6 1676.3 1732.8 1733.4 1783.0 1795.1 80391.4 99476.0 131543.0 132282.0 133702.0 11392.9 11709.4 11767.6 11857.8 12175.3 15211.2 15266.2 15284.4 15295.6 15310.8 8026.0 8516.0 8757.7 8838.1 8872.2 8630.9 8661.3 8683.0 8757.5 8772.7 3889.8 4620.8 4627.3 4635.8 4690.5 1700.4 1742.8 1790.8 1889.3 1921.5 858.3 877.7 888.9 917.6 975.4 19763.1 20142.4 20194.2 20449.7 20586.4 902.2 902.2 962.9 986.0 1021.5 10005.0 10857.2 10892.5 10895.7 10909.9 593236.0 595845.0 599664.0 609039.0 610341.0 121682.4 122667.0 124706.0 125201.4 129079.0 907.0 1008.9 1017.3 1044.3 1045.0 14622.2 18507.0 18600.9 19133.2 19172.0 51091.7 52273.0 52626.4 53496.7 53794.2 75884.6 82963.0 83001.0 85083.6 87204.7 7459.7 7915.0 8060.6 8110.5 8130.5 9036.3 11722.7 12362.6 12410.5 12542.9 11027.6 11112.2 11118.9 11126.2 11479.9 9368.9 10804.2 11044.6 11190.7 11291.5 5774.6 8978.4 10939.3 11223.6 11613.8 7775.0 8056.3 8115.5 8133.3 8353.0 8010.8 9967.7 11059.5 11387.5 11392.2 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | [#3] mootools.html Cycles:180 Average:5716.78 Median:4946.83 stddev:3180.06 (64.3%) stddev-sans-first:3172.35 10:59:52 INFO - PROCESS | 1962 | Values: 1389.8 1634.7 1663.9 1881.7 1986.4 7724.7 7826.8 8580.4 8902.0 8980.3 8577.4 8830.0 8831.0 8833.4 8841.4 2042.5 2108.7 2403.8 2439.6 2486.4 2478.9 2507.2 2519.0 2523.2 2525.1 2432.0 2446.4 2477.9 2479.7 2483.3 2150.4 2235.4 2237.5 2420.4 2472.3 2336.5 2421.6 2423.2 2424.5 2425.5 8706.9 8767.8 8782.8 8786.3 8792.8 5211.1 5385.2 5394.1 5421.9 5425.5 3012.9 3037.4 3457.1 3616.7 3638.6 1002.3 1052.2 1053.3 1055.7 1062.3 11517.4 11521.9 11524.1 11531.0 11560.6 4771.8 4810.2 4893.5 4902.2 4937.9 9855.6 9871.1 9878.7 9889.5 9908.6 5408.0 5421.3 5448.7 5466.2 5479.0 4445.7 4526.0 4780.1 4890.6 4919.4 3484.9 3970.0 3992.2 4089.1 4121.6 1123.5 1294.1 1354.7 1365.1 1378.5 910.5 928.2 930.6 950.6 980.0 5880.5 5919.2 6001.3 6143.5 6235.3 962.7 980.0 1003.3 1003.4 1003.9 3895.0 3968.1 4000.3 4038.3 4066.7 8901.7 11035.7 11038.0 11043.0 11047.8 10061.8 10900.9 10937.9 10963.6 10969.8 8228.9 8305.0 8308.1 8390.6 8394.8 5625.5 5820.2 5906.5 5988.0 6117.5 9695.9 9736.2 9804.3 10095.8 10226.9 10123.3 10190.9 10286.2 10316.8 10330.6 6351.7 6480.6 6624.9 7026.3 7166.6 7903.8 8557.0 8557.2 8563.8 8586.1 6506.4 7492.4 7519.8 7531.5 7704.5 7389.4 7446.6 7517.4 7525.9 7525.9 6924.8 7079.6 7089.5 7096.1 7106.3 4451.2 4923.5 4955.8 5028.6 5179.9 4956.7 4992.6 5012.8 5040.2 5231.2 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | [#4] prototype.html Cycles:180 Average:4343.23 Median:4769.65 stddev:1344.60 (28.2%) stddev-sans-first:1313.86 10:59:52 INFO - PROCESS | 1962 | Values: 310.7 311.8 316.7 321.8 323.3 5061.4 5147.4 5179.8 5187.2 5227.6 4351.8 4461.4 4671.9 5057.8 5195.5 3494.9 3499.1 3509.3 3796.3 3834.3 3301.6 3742.0 3867.8 3937.7 3944.9 3618.4 3898.6 3912.5 3937.8 3975.7 3716.2 3741.9 3784.7 3929.8 3968.9 3208.5 3452.2 3474.1 3477.3 3487.6 4009.4 4207.3 4778.5 4934.2 4972.9 4247.7 4480.2 4490.9 4542.8 4559.4 4745.0 4983.8 5269.6 5329.7 5334.0 3304.3 3354.0 3687.7 3770.0 3796.2 4124.4 5292.8 5597.8 5714.0 5736.0 3476.6 3734.2 3739.4 3754.4 3774.0 3316.8 3695.7 3809.6 3820.3 3824.5 4417.4 4436.9 4438.4 4452.9 4486.8 4578.2 4661.0 4998.3 5333.5 5471.8 1149.1 1166.5 1278.5 1283.5 1305.3 809.3 848.7 853.9 911.6 912.6 4268.5 5045.9 5060.7 5085.6 5097.7 4898.0 5021.5 5553.4 5586.3 5600.4 4422.4 5059.8 5068.3 5106.3 5229.5 2368.5 2474.2 2658.1 2825.7 2833.3 5428.6 5551.0 5581.2 5655.4 5679.7 4760.8 4833.2 5370.9 5463.8 5469.9 5791.2 5811.6 5812.5 5836.8 5850.6 5461.9 5473.5 5587.6 5647.7 5676.7 4209.3 5114.4 5255.2 5606.5 5694.3 5234.7 5719.4 5806.4 5858.0 5896.2 3763.8 3818.6 3824.4 3884.8 3884.9 4344.8 5467.9 5680.2 5705.8 5753.9 4278.4 5526.3 5537.5 5573.7 5603.7 4750.9 5101.8 5179.1 5434.1 5545.7 4293.2 4696.8 4755.0 4805.9 5345.5 4589.5 4909.3 5041.5 5242.7 5436.9 4447.9 5343.3 5364.0 5398.1 5408.2 10:59:52 INFO - PROCESS | 1962 | 10:59:52 INFO - PROCESS | 1962 | [#5] yui.html Cycles:180 Average:16944.71 Median:578.54 stddev:22656.42 (3916.1%) stddev-sans-first:22687.05 10:59:52 INFO - PROCESS | 1962 | Values: 677.9 704.5 716.2 740.2 746.6 16650.8 17177.5 17238.0 17585.9 17603.6 15073.5 16248.3 16256.8 16263.5 16267.8 720.8 749.6 927.2 931.7 933.2 894.0 930.2 935.9 937.5 940.2 967.7 973.2 979.3 980.2 980.5 742.5 924.2 971.7 971.8 972.1 721.9 725.4 726.1 726.5 727.1 15003.8 15103.8 15111.3 15114.5 15146.0 12059.5 12176.9 12257.7 12406.3 12487.3 10780.4 10939.9 11058.8 11358.0 11643.7 4013.5 4029.1 4068.3 4142.1 4163.9 82037.2 82637.9 82869.0 83371.6 83607.6 8078.4 9391.2 9421.8 9535.6 9608.7 20746.6 20786.6 20897.8 20921.2 21155.9 7400.1 7443.4 7445.2 7552.2 7742.3 4044.5 4108.4 4108.8 4109.2 4148.6 3462.4 3559.9 3562.1 3563.6 3572.4 640.3 650.3 660.5 662.5 665.6 484.5 485.8 528.9 561.5 567.0 7866.8 7873.9 7908.6 8017.4 8076.4 507.5 510.7 573.9 583.2 583.6 3574.2 3982.9 4138.9 4298.6 4390.5 61416.0 67698.3 69637.0 69637.7 69734.6 71220.9 71366.0 73529.6 73645.3 79850.4 60670.4 61851.0 62706.0 74095.6 74717.6 8255.9 8340.4 8348.9 8451.0 8572.7 40065.0 40947.8 41121.4 42727.6 43806.0 45140.6 51047.0 57012.7 57113.7 57182.0 10973.3 11067.3 11122.6 11157.0 11302.7 11845.3 11926.8 12235.9 12323.0 12764.3 9073.5 10850.5 10914.3 11407.4 11699.9 9514.9 11129.4 11155.3 11177.8 11640.1 10892.3 11058.6 11077.7 11089.3 11258.3 9268.4 9409.1 9417.2 9591.7 9723.6 9322.3 9421.6 9426.2 9512.0 9699.5 10:59:52 INFO - PROCESS | 1962 | -------- Summary: end -------- 10:59:52 INFO - PROCESS | 1962 | 10:59:53 INFO - TEST-INFO | 1962: exit 0 10:59:53 INFO - TEST-OK | dromaeo_css | took 1116253ms 10:59:53 INFO - TEST-START | kraken 10:59:53 INFO - operating with platform_type : mac_ 10:59:53 INFO - Initialising browser for kraken test... 10:59:53 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpDt22h0/profile http://localhost:50280/getInfo.html) 10:59:55 INFO - PROCESS | 1966 | __metrics Screen width/height:1600/1200 10:59:55 INFO - PROCESS | 1966 | colorDepth:24 10:59:55 INFO - PROCESS | 1966 | Browser inner width/height: 1024/689 10:59:55 INFO - PROCESS | 1966 | __metrics 10:59:55 INFO - PROCESS | 1966 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:59:55 ERROR - PROCESS | 1966 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:59:56 INFO - TEST-INFO | 1966: exit 0 10:59:56 INFO - Browser initialized. 10:59:56 INFO - Running cycle 1/1 for kraken test... 10:59:56 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpDt22h0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:00:08 INFO - PROCESS | 1968 | RSS: Main: 238927872 11:00:08 INFO - PROCESS | 1968 | 11:00:10 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50280/tests/kraken/driver.html?testName=audio-beat-detection) 11:00:11 INFO - PROCESS | 1968 | RSS: Main: 286085120 11:00:11 INFO - PROCESS | 1968 | 11:00:14 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50280/tests/kraken/driver.html?testName=audio-dft) 11:00:14 INFO - PROCESS | 1968 | RSS: Main: 294981632 11:00:14 INFO - PROCESS | 1968 | 11:00:18 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50280/tests/kraken/driver.html?testName=audio-fft) 11:00:19 INFO - PROCESS | 1968 | RSS: Main: 382861312 11:00:19 INFO - PROCESS | 1968 | 11:00:21 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50280/tests/kraken/driver.html?testName=audio-oscillator) 11:00:21 INFO - PROCESS | 1968 | RSS: Main: 282222592 11:00:21 INFO - PROCESS | 1968 | 11:00:23 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:00:24 INFO - PROCESS | 1968 | RSS: Main: 286384128 11:00:24 INFO - PROCESS | 1968 | 11:00:27 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50280/tests/kraken/driver.html?testName=imaging-darkroom) 11:00:27 INFO - PROCESS | 1968 | RSS: Main: 325197824 11:00:27 INFO - PROCESS | 1968 | 11:00:30 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50280/tests/kraken/driver.html?testName=imaging-desaturate) 11:00:31 INFO - PROCESS | 1968 | RSS: Main: 325144576 11:00:31 INFO - PROCESS | 1968 | 11:00:34 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50280/tests/kraken/driver.html?testName=json-parse-financial) 11:00:34 INFO - PROCESS | 1968 | RSS: Main: 324370432 11:00:34 INFO - PROCESS | 1968 | 11:00:36 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:00:37 INFO - PROCESS | 1968 | RSS: Main: 312414208 11:00:37 INFO - PROCESS | 1968 | 11:00:39 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:00:39 INFO - PROCESS | 1968 | RSS: Main: 319225856 11:00:39 INFO - PROCESS | 1968 | 11:00:41 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:00:42 INFO - PROCESS | 1968 | RSS: Main: 321486848 11:00:42 INFO - PROCESS | 1968 | 11:00:45 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:00:46 INFO - PROCESS | 1968 | RSS: Main: 325808128 11:00:46 INFO - PROCESS | 1968 | 11:00:49 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:00:49 INFO - PROCESS | 1968 | RSS: Main: 323784704 11:00:49 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:50280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50280/tests/kraken/driver.html?testName=ai-astar) 11:00:51 INFO - PROCESS | 1968 | RSS: Main: 370331648 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | __start_tp_report 11:00:51 INFO - PROCESS | 1968 | _x_x_mozilla_page_load 11:00:51 INFO - PROCESS | 1968 | _x_x_mozilla_page_load_details 11:00:51 INFO - PROCESS | 1968 | |i|pagename|runs| 11:00:51 INFO - PROCESS | 1968 | |0;ai-astar;111;104;113;113;112;113;112;112;112;112 11:00:51 INFO - PROCESS | 1968 | |1;audio-beat-detection;155;104;136;188;138;159;135;119;166;123 11:00:51 INFO - PROCESS | 1968 | |2;audio-dft;184;186;185;186;187;182;184;185;195;225 11:00:51 INFO - PROCESS | 1968 | |3;audio-fft;78;73;57;108;57;61;56;59;113;55 11:00:51 INFO - PROCESS | 1968 | |4;audio-oscillator;93;93;100;79;80;75;109;110;110;109 11:00:51 INFO - PROCESS | 1968 | |5;imaging-gaussian-blur;87;84;86;84;86;83;87;83;85;99 11:00:51 INFO - PROCESS | 1968 | |6;imaging-darkroom;92;103;141;94;93;99;95;93;130;93 11:00:51 INFO - PROCESS | 1968 | |7;imaging-desaturate;90;130;86;82;85;69;128;131;94;80 11:00:51 INFO - PROCESS | 1968 | |8;json-parse-financial;57;59;58;57;57;56;57;78;59;58 11:00:51 INFO - PROCESS | 1968 | |9;json-stringify-tinderbox;64;64;75;75;70;72;69;66;70;95 11:00:51 INFO - PROCESS | 1968 | |10;stanford-crypto-aes;84;96;104;86;86;83;81;87;84;86 11:00:51 INFO - PROCESS | 1968 | |11;stanford-crypto-ccm;139;135;141;134;134;140;139;138;196;148 11:00:51 INFO - PROCESS | 1968 | |12;stanford-crypto-pbkdf2;166;187;219;193;170;221;172;165;166;167 11:00:51 INFO - PROCESS | 1968 | |13;stanford-crypto-sha256-iterative;62;64;65;67;66;65;65;65;67;66 11:00:51 INFO - PROCESS | 1968 | __end_tp_report 11:00:51 INFO - PROCESS | 1968 | __start_cc_report 11:00:51 INFO - PROCESS | 1968 | _x_x_mozilla_cycle_collect,2000 11:00:51 INFO - PROCESS | 1968 | __end_cc_report 11:00:51 INFO - PROCESS | 1968 | __startTimestamp1462644051679__endTimestamp 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | ------- Summary: start ------- 11:00:51 INFO - PROCESS | 1968 | Number of tests: 14 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.67 (2.4%) stddev-sans-first:2.83 11:00:51 INFO - PROCESS | 1968 | Values: 111.0 104.0 113.0 113.0 112.0 113.0 112.0 112.0 112.0 112.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:146.50 stddev:24.91 (17.0%) stddev-sans-first:25.99 11:00:51 INFO - PROCESS | 1968 | Values: 155.0 104.0 136.0 188.0 138.0 159.0 135.0 119.0 166.0 123.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#2] audio-dft Cycles:10 Average:189.90 Median:186.00 stddev:12.81 (6.9%) stddev-sans-first:13.41 11:00:51 INFO - PROCESS | 1968 | Values: 184.0 186.0 185.0 186.0 187.0 182.0 184.0 185.0 195.0 225.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#3] audio-fft Cycles:10 Average:71.70 Median:58.00 stddev:21.85 (37.7%) stddev-sans-first:23.06 11:00:51 INFO - PROCESS | 1968 | Values: 78.0 73.0 57.0 108.0 57.0 61.0 56.0 59.0 113.0 55.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:77.00 stddev:13.94 (18.1%) stddev-sans-first:14.75 11:00:51 INFO - PROCESS | 1968 | Values: 93.0 93.0 100.0 79.0 80.0 75.0 109.0 110.0 110.0 109.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:86.00 stddev:4.67 (5.4%) stddev-sans-first:4.95 11:00:51 INFO - PROCESS | 1968 | Values: 87.0 84.0 86.0 84.0 86.0 83.0 87.0 83.0 85.0 99.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:93.00 stddev:17.49 (18.8%) stddev-sans-first:18.07 11:00:51 INFO - PROCESS | 1968 | Values: 92.0 103.0 141.0 94.0 93.0 99.0 95.0 93.0 130.0 93.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:83.50 stddev:23.15 (27.7%) stddev-sans-first:24.40 11:00:51 INFO - PROCESS | 1968 | Values: 90.0 130.0 86.0 82.0 85.0 69.0 128.0 131.0 94.0 80.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:58.00 stddev:6.54 (11.3%) stddev-sans-first:6.86 11:00:51 INFO - PROCESS | 1968 | Values: 57.0 59.0 58.0 57.0 57.0 56.0 57.0 78.0 59.0 58.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.00 stddev:8.99 (12.7%) stddev-sans-first:9.06 11:00:51 INFO - PROCESS | 1968 | Values: 64.0 64.0 75.0 75.0 70.0 72.0 69.0 66.0 70.0 95.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:86.00 stddev:6.98 (8.1%) stddev-sans-first:7.27 11:00:51 INFO - PROCESS | 1968 | Values: 84.0 96.0 104.0 86.0 86.0 83.0 81.0 87.0 84.0 86.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:139.50 stddev:18.59 (13.3%) stddev-sans-first:19.62 11:00:51 INFO - PROCESS | 1968 | Values: 139.0 135.0 141.0 134.0 134.0 140.0 139.0 138.0 196.0 148.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.60 Median:179.50 stddev:21.86 (12.2%) stddev-sans-first:22.35 11:00:51 INFO - PROCESS | 1968 | Values: 166.0 187.0 219.0 193.0 170.0 221.0 172.0 165.0 166.0 167.0 11:00:51 INFO - PROCESS | 1968 | 11:00:51 INFO - PROCESS | 1968 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.48 (2.3%) stddev-sans-first:1.01 11:00:51 INFO - PROCESS | 1968 | Values: 62.0 64.0 65.0 67.0 66.0 65.0 65.0 65.0 67.0 66.0 11:00:51 INFO - PROCESS | 1968 | -------- Summary: end -------- 11:00:51 INFO - PROCESS | 1968 | 11:00:52 INFO - TEST-INFO | 1968: exit 0 11:00:52 INFO - TEST-OK | kraken | took 59421ms 11:00:52 INFO - SUITE-END | took 1175s 11:00:52 INFO - Completed test suite (00:19:35) 11:00:52 INFO - javascript benchmark 11:00:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3223.693749093836, 3439.86240550378, 3471.9132021699456, 3477.4436090225536, 3494.4932984717216, 5662.688552129631, 5735.455131762481, 5743.798967036155, 5822.155787410828, 5863.706814659266, 5758.798442054529, 5837.854053648651, 5852.912206316909, 5860.062390017598, 5876.647401155923, 5249.527542521172, 5270.578353731692, 5326.334208223972, 5336.706481143536, 5369.40936496987, 5346.251524786514, 5353.732313384335, 5369.0, 5384.757685904144, 5389.892202155955, 5259.842204733845, 5268.420473747885, 5292.894142117156, 5294.894102117955, 5361.544268737162, 5366.06093933564, 5372.409035006146, 5380.408155102936, 5381.031414345416, 5401.6218864679095, 5112.718800465973, 5225.634205605609, 5242.475752424765, 5287.682739035632, 5288.65623734456, 5660.490555849993, 5690.60165788395, 5691.288588926384, 5716.799912003102, 5720.0, 6240.31356550779, 6244.656543890084, 6258.405451482147, 6271.498280137578, 6280.905786413207, 5713.885722285572, 5958.553108516835, 6207.286162091372, 6233.625982441023, 6242.937570624281, 5918.763249470059, 5951.226340575698, 5967.075103358986, 5968.283805943272, 5973.761049557969, 6288.5598008139905, 6291.339409362043, 6302.779402720883, 6307.148534947723, 6310.432061114522, 5311.920321195185, 6057.727402266909, 6066.575339726177, 6144.846378840565, 6242.375762423721, 6275.090111933835, 6292.968535157386, 6299.464545513682, 6301.0, 6301.810945671546, 6204.410580994799, 6275.058741188767, 6281.0, 6285.214348206474, 6312.652804095727, 6104.883608894094, 6180.381961803873, 6215.254169499599, 6276.780312689034, 6297.338779428185, 4877.68295060827, 4889.193283108319, 4904.509549045067, 4916.410030796327, 4931.90136197274, 4412.271975124068, 4416.098614234671, 4419.027813881005, 4438.090191510732, 4475.060237350128, 5703.771849125988, 5858.970705146532, 5864.06175011996, 5876.324222714357, 5904.940950590525, 6146.58628515444, 6303.0, 6305.369463053749, 6311.558190926683, 6325.209348831396, 5809.244798176294, 5821.621594596338, 5876.265466816648, 5893.469587737124, 5895.55783316253, 5427.6200665953, 5439.5648348132845, 5445.37378201504, 5450.073487507133, 5450.809221677222, 6222.284437289589, 6282.811515654537, 6371.7132729028235, 6373.71318290678, 6412.102305677209, 6260.780872669435, 6305.558610897377, 6307.779227727007, 6316.747330106744, 6356.936430635635, 6343.809685709436, 6352.777652782131, 6364.109024736633, 6377.840553986188, 6387.712552935222, 6098.886104840251, 6142.0, 6182.969085154546, 6216.53375996793, 6231.5637905348, 5412.972935135299, 5657.575681823962, 6242.126102345766, 6259.874802503833, 6311.400416960381, 6194.318624951162, 6209.2548894133, 6213.471854892385, 6234.345393733593, 6236.75052997875, 5937.584369094122, 5945.672987985702, 6151.630902145886, 6152.477039451696, 6160.137580738789, 5769.567282453749, 5915.40845915405, 5974.2830860297045, 5974.462298393166, 6053.243344581927, 5535.972320138373, 6114.174586430775, 6138.815835524942, 6163.291221509584, 6177.660228687465, 5854.063349864001, 5864.384239654775, 5986.102084687332, 6027.156198132177, 6054.0], "value": 5741.43938135986, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.1446975028576, 493.0976313346576, 498.76059491444107, 502.5954106943911, 520.9034981364229, 31769.36461270776, 33433.49849752256, 45272.86817829556, 47361.52638473623, 47446.76276618612, 44880.3267950981, 45239.321410178876, 45257.77371113148, 45359.464053594624, 45364.0, 9721.465319407413, 9886.110250077492, 9887.0, 9909.454979976099, 9913.306068575203, 8688.044315125331, 8897.599608017623, 11597.47811348487, 11855.229410088317, 11873.109516786275, 11730.472128754183, 11799.115066370014, 11822.527098916076, 11828.645140645795, 11834.230774999598, 8756.649734010633, 11591.768164636744, 11661.24201926872, 11734.647960561197, 11736.823947640793, 11458.598949036827, 11640.592579259684, 11644.767104657943, 11658.183927125105, 11660.242084264495, 41501.79249103765, 41907.580924190675, 41951.370729439084, 42022.78988605068, 42146.789266053784, 3274.983625081883, 3290.2596915694016, 3385.9830700846583, 3530.4174811156126, 3623.873164439232, 29670.0, 29741.85129074341, 29840.701592984227, 30092.398152037054, 30518.084457466313, 2559.539282929072, 2758.848263345535, 2761.240658818821, 2789.4560560690675, 2794.7624451921406, 508623.0, 512759.43620282406, 513168.43415783055, 513257.0, 513484.0, 35874.0, 36715.449268261, 67568.0, 68227.65886170538, 68264.6586767063, 37646.811765941, 38034.809825950695, 38075.0, 38106.42840357397, 38115.23769524649, 15340.079595224322, 15452.459163929208, 15495.535133946, 15505.689886202439, 15525.611859703371, 11721.882781172078, 11917.0, 12749.808752868528, 12880.613581592566, 12990.545330913561, 8194.262516373556, 8243.793905152419, 8263.876041859377, 8346.04020537646, 8351.039630442456, 3378.44255697812, 3400.6429324921023, 3403.217260030207, 3409.7783644063056, 3429.794212347217, 956.8469044952914, 1033.1682995188794, 1039.887320567001, 1093.814051611228, 1134.6993046842592, 29100.0, 29494.705052949197, 29871.402571948438, 30109.849450752605, 30166.84916575403, 1092.8252128961399, 1104.4919337104861, 1145.8682251541013, 1160.8606967163994, 1188.1504724122121, 15379.384824607114, 15561.610959725871, 15565.455209067855, 15769.0, 15870.60323491922, 659931.0, 663701.0, 665232.0, 670064.0, 670884.0, 58225.417745822, 63109.68445157745, 63189.0, 63225.0, 63242.36757632457, 1295.4947570447532, 1297.7339645372676, 1331.8601546837635, 1440.1575078579149, 1472.249152931991, 29751.55372669455, 30425.23936901595, 30476.390472190877, 30913.51753995613, 31386.05841824749, 48224.0, 52519.0, 53169.734151330544, 53661.731691341294, 54047.72976135094, 505535.0, 506357.0, 506789.0, 507180.0, 507916.0, 23966.52066958686, 26958.730412696408, 27534.311642209104, 27830.16509504718, 28658.0, 46038.0, 46124.07751844877, 46465.07069858652, 46926.53073469358, 47744.2838357418, 35961.0, 38102.80948595239, 38134.046648834, 38845.640402586905, 39147.41278880876, 36028.45957310695, 37622.8118859404, 37989.430158548195, 38502.61497385102, 38756.41865372078, 37486.43770343393, 37747.43378849264, 38437.23125537418, 38470.8458746238, 38495.615043849204, 27263.0, 33461.83269083639, 33596.83201583976, 33618.49572256467, 33630.4598879158, 21275.344111591832, 21939.92048445719, 24278.87860560686, 25268.0, 25674.8716256425], "value": 19126.206254719167, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.7576759919455, 373.79814899954016, 393.2606699405118, 408.42616124345267, 417.2302102620666, 19355.612887742252, 21054.57890842186, 21930.671039934412, 21941.24470042397, 21984.89007554964, 20285.59428811423, 21202.0, 21319.0, 21374.679379809317, 21728.13087476499, 5034.949650503503, 5456.7544460499375, 5471.452854714516, 5472.5621950243885, 5477.863053423676, 3843.1737176507045, 4040.0101975016164, 5346.572274218072, 5682.403347648499, 6006.969965150168, 5251.0, 6024.21685180928, 6030.638161710312, 6033.7888173913925, 6051.818445446644, 5736.856578585527, 5974.6713930733795, 5977.342492325863, 5983.491403230731, 5984.521238300926, 3727.1986522897437, 3957.663598594123, 3958.604139586047, 5446.809361672362, 5735.369109397963, 11796.29222246668, 11928.343941083236, 12025.819612705816, 13736.587902362946, 13785.0, 2112.5141217519904, 2148.1729534129317, 2164.404788683125, 2400.663907053014, 2495.887685054177, 9893.406395616214, 9969.65106221286, 10184.94907525465, 12892.400683107035, 14459.638509037359, 1676.2791999440235, 1732.7747392838976, 1733.3586572968084, 1782.97325540117, 1795.0755360988971, 80391.39217215624, 99476.0, 131543.0, 132282.0, 133702.0, 11392.943035284936, 11709.355985420883, 11767.588134415255, 11857.822132668018, 12175.269483830998, 15211.239438028055, 15266.236688165547, 15284.38862445512, 15295.617609559627, 15310.779936909086, 8026.0, 8516.032439176544, 8757.737267881974, 8838.07200243965, 8872.157145071207, 8630.870536941951, 8661.30709543235, 8683.0, 8757.518336491554, 8772.736817895473, 3889.8444062237195, 4620.815167393266, 4627.311031473488, 4635.791389387486, 4690.530946905282, 1700.4218565687725, 1742.8257174282471, 1790.8449050362444, 1889.3198448558508, 1921.5100149461798, 858.3176374782063, 877.6884206106868, 888.9333300002526, 917.5824999625288, 975.4293738163254, 19763.110660020335, 20142.39572812818, 20194.192232310837, 20449.693254601192, 20586.382408527767, 902.2240872849344, 902.2376092202079, 962.9037096290455, 986.0090608937971, 1021.5198856537415, 10005.0, 10857.239993200557, 10892.509837057352, 10895.727606809893, 10909.890901090888, 593236.0, 595845.0, 599664.0, 609039.0, 610341.0, 121682.39158804326, 122667.0, 124706.0, 125201.37399312945, 129079.0, 906.9727908162633, 1008.8940661230612, 1017.3285631483334, 1044.3159730376615, 1044.984325235122, 14622.195779232245, 18507.0, 18600.86090099656, 19133.234670613576, 19172.0, 51091.744541277054, 52272.954540909726, 52626.42120736384, 53496.66258343417, 53794.193087104504, 75884.62057689676, 82963.0, 83001.0, 85083.57458212669, 87204.69192961983, 7459.664315105942, 7915.0, 8060.556669383241, 8110.472819266729, 8130.471519351223, 9036.322275829207, 11722.706932326419, 12362.567310144103, 12410.503579856704, 12542.874571254171, 11027.558897644325, 11112.166587506132, 11118.944405277922, 11126.165537584875, 11479.885201147881, 9368.906310936803, 10804.189685773441, 11044.558217671203, 11190.66428007161, 11291.548338066385, 5774.624649397776, 8978.371513993958, 10939.3044078168, 11223.55105795759, 11613.825792612948, 7775.028121484815, 8056.274935255855, 8115.462437828386, 8133.292008678244, 8352.958235208785, 8010.799730006796, 9967.700968970943, 11059.50232239535, 11387.487563059516, 11392.202545821712], "value": 9222.842958373889, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.7776355783076, 1634.7466142747874, 1663.883528153029, 1881.6895212289974, 1986.3544348086866, 7724.729634462795, 7826.8043298917555, 8580.356473264499, 8902.0, 8980.281577473803, 8577.399582029246, 8829.984551776548, 8831.02858685544, 8833.381663283573, 8841.425307355017, 2042.5200077981663, 2108.746950365958, 2403.831731778776, 2439.634054891763, 2486.415692312305, 2478.9008439662393, 2507.210228777938, 2519.004993027753, 2523.1799665108915, 2525.090967251797, 2431.9513609727796, 2446.3639453742067, 2477.8884950177285, 2479.6528486011885, 2483.3170878008514, 2150.4086376246655, 2235.418791114322, 2237.518933429304, 2420.4070002849326, 2472.2830379189922, 2336.5093330400637, 2421.61254199329, 2423.2245681381964, 2424.5393375258823, 2425.4906469641396, 8706.869396959051, 8767.78080547985, 8782.780430489289, 8786.341024423129, 8792.824143517159, 5211.140161873286, 5385.165299378603, 5394.109971854639, 5421.891562168774, 5425.538829199523, 3012.9246768830953, 3037.3925214956876, 3457.1184347991352, 3616.7106631469683, 3638.6361363863666, 1002.2633364477103, 1052.1950707708609, 1053.3206082077018, 1055.7413433708714, 1062.3466568060664, 11517.36654484003, 11521.942390288079, 11524.078073754163, 11531.019863311629, 11560.595379161774, 4771.832985845548, 4810.230363141881, 4893.486183950634, 4902.191138462185, 4937.876553086202, 9855.605775769032, 9871.062249086326, 9878.654247101316, 9889.505524723734, 9908.554115064839, 5408.026555220098, 5421.26812880264, 5448.700321482356, 5466.1527463243265, 5478.986387518322, 4445.711028783118, 4525.977370113128, 4780.139574876486, 4890.58430033444, 4919.434265059493, 3484.9477257841154, 3969.960300397017, 3992.201559688074, 4089.0799570097083, 4121.608447197512, 1123.455124264731, 1294.1264646363668, 1354.7222819322014, 1365.0990346371545, 1378.4968486502555, 910.4582773249906, 928.2017464980112, 930.6184464369576, 950.5959967013991, 980.0444566547701, 5880.470757631833, 5919.230500035054, 6001.309849367379, 6143.539234557426, 6235.251769787564, 962.6726912849665, 979.9955046077828, 1003.2826529031777, 1003.4380746781826, 1003.934744241622, 3895.0, 3968.1270120573427, 4000.3399439092223, 4038.273110840018, 4066.6949978751713, 8901.73294801157, 11035.668929932115, 11037.9513946175, 11043.0, 11047.83428248577, 10061.79876402468, 10900.890991089987, 10937.890621093846, 10963.56145754177, 10969.780604388026, 8228.917710822934, 8305.0, 8308.086110527836, 8390.622421991147, 8394.8321033579, 5625.46558076982, 5820.185174075717, 5906.468417842415, 5987.970060149672, 6117.510599152146, 9695.854562181432, 9736.22110231194, 9804.313698041069, 10095.798084038424, 10226.926172751753, 10123.291369604352, 10190.898091019291, 10286.177105831664, 10316.793664126526, 10330.586776529155, 6351.745930162741, 6480.57876238043, 6624.924660161267, 7026.332498412847, 7166.605836679033, 7903.802404939923, 8557.015943166616, 8557.229849313591, 8563.82872342562, 8586.141385861363, 6506.4144227017905, 7492.438067145095, 7519.774406767806, 7531.510451820834, 7704.499207551491, 7389.445791565547, 7446.590437525988, 7517.436192285491, 7525.924740752523, 7525.924740752742, 6924.757633482804, 7079.575225486487, 7089.468289878383, 7096.112985876765, 7106.253843346374, 4451.1765323415275, 4923.507649235048, 4955.776990035385, 5028.572571331478, 5179.870503237449, 4956.677815941952, 4992.6255530834605, 5012.824551140692, 5040.193569028937, 5231.189165679326], "value": 4618.4281723264885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.709486630001, 311.7793837127645, 316.74660271782585, 321.7892280556236, 323.3145731050174, 5061.443241243465, 5147.433782283946, 5179.766910489027, 5187.22191671249, 5227.581793456524, 4351.760653164075, 4461.442319710036, 4671.925457144859, 5057.797688092481, 5195.532402083812, 3494.912627184315, 3499.1077275294765, 3509.298140371923, 3796.335641262782, 3834.332065551494, 3301.6038075430984, 3741.9896627910584, 3867.7679339239735, 3937.7243592948507, 3944.895429279792, 3618.3848745713162, 3898.629630185128, 3912.471816304791, 3937.763734175944, 3975.6819454443576, 3716.2195938852874, 3741.9064523386996, 3784.6972242220554, 3929.783861887595, 3968.940465893014, 3208.470602350609, 3452.188735647121, 3474.1314671332166, 3477.2523907360037, 3487.5814902211896, 4009.4386785850006, 4207.347861081537, 4778.498257682928, 4934.1611925972675, 4972.90054198914, 4247.7451352918615, 4480.215962206634, 4490.854832017847, 4542.795574199169, 4559.430071241061, 4744.976275118603, 4983.825566105169, 5269.578433725293, 5329.680219186823, 5334.0, 3304.339132173575, 3353.96646033539, 3687.690869741257, 3769.963260103466, 3796.2217745362136, 4124.381342798544, 5292.841214763564, 5597.832065038056, 5714.028705073339, 5735.999999999958, 3476.634953329864, 3734.2158146788934, 3739.439084137401, 3754.3617585010616, 3773.9621604058552, 3316.7512436567354, 3695.667389934919, 3809.6380244248744, 3820.2741479118877, 3824.4836947012354, 4417.425734654538, 4436.866893993185, 4438.356438316426, 4452.933206001912, 4486.775661216926, 4578.153041687232, 4660.953390466119, 4998.325226094431, 5333.4931982474745, 5471.8084867029465, 1149.057772626455, 1166.5333866453486, 1278.520554791953, 1283.5250957145915, 1305.2690493323769, 809.336344197764, 848.6605357856783, 853.9283199584606, 911.5988964855443, 912.5756523216736, 4268.487781466182, 5045.8738531537, 5060.671056381323, 5085.618578606619, 5097.745112744348, 4898.044881248194, 5021.472745361758, 5553.416891226363, 5586.301712285965, 5600.35595906476, 4422.380866678667, 5059.797608095709, 5068.341115654941, 5106.336176297058, 5229.450907654717, 2368.4539120445247, 2474.241221038565, 2658.142496815931, 2825.671934190927, 2833.2916770807296, 5428.647137936022, 5551.0, 5581.190727344512, 5655.3779084300695, 5679.716014199356, 4760.809567617256, 4833.154198015333, 5370.8657283567445, 5463.808766693147, 5469.9214298567085, 5791.247137872049, 5811.622244554091, 5812.505936995402, 5836.768342951326, 5850.590458667937, 5461.890762184814, 5473.452654734495, 5587.636803607753, 5647.689377084135, 5676.68778217202, 4209.326507758744, 5114.411842638145, 5255.2379904912805, 5606.495415412469, 5694.259746232963, 5234.685918844882, 5719.370869204467, 5806.390329015392, 5858.041688947054, 5896.233489646319, 3763.8494460221286, 3818.6181381861593, 3824.4263360495033, 3884.8057597120596, 3884.9223015538964, 4344.782760862007, 5467.906418716193, 5680.233168522197, 5705.771769129188, 5753.942460575341, 4278.401023856725, 5526.281583394133, 5537.501624853621, 5573.665580065209, 5603.663780173202, 4750.9287360690305, 5101.821436249714, 5179.1195496764885, 5434.1033729435785, 5545.724483368767, 4293.227219100592, 4696.765161741968, 4755.025219829996, 4805.903881922272, 5345.54562862161, 4589.518100599389, 4909.3126962224605, 5041.546260836542, 5242.749495312931, 5436.891262174813, 4447.866564003085, 5343.278657381204, 5363.980843639696, 5398.109311963639, 5408.188771684278], "value": 3923.823609807717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.86103848711, 704.4505285877015, 716.1585137463479, 740.171008470513, 746.5557992994167, 16650.83349166508, 17177.484675459757, 17238.0, 17585.912070439663, 17603.647927041453, 15073.547793566211, 16248.268827902773, 16256.75614865778, 16263.51209463715, 16267.755983660225, 720.765751130882, 749.6027105634023, 927.1562877781238, 931.6599441203946, 933.2160984772789, 893.9731808045769, 930.1907340613676, 935.9438433693966, 937.4843835890233, 940.2384068904175, 967.7387105481516, 973.1922504321396, 979.2704435195789, 980.2158273381302, 980.5391466010964, 742.4580056558742, 924.191332583989, 971.6696323250059, 971.7522031881896, 972.1493693018608, 721.9061522002158, 725.365305357812, 726.1032624708481, 726.513236131793, 727.1383410658386, 15003.849961500355, 15103.848961510354, 15111.31999060045, 15114.470993515284, 15146.0, 12059.517619295219, 12176.878231217752, 12257.693557661041, 12406.317652529107, 12487.31319777412, 10780.353178809297, 10939.890601094046, 11058.778824423547, 11358.0, 11643.708907277301, 4013.538443079054, 4029.133736246721, 4068.288049591339, 4142.130152667944, 4163.91672166555, 82037.17962820355, 82637.9340516492, 82869.0, 83371.5831420839, 83607.58196209041, 8078.35373170145, 9391.248700103975, 9421.764455888657, 9535.618575256913, 9608.711738647851, 20746.585068298853, 20786.58426831485, 20897.79102208989, 20921.163153473994, 21155.8942205288, 7400.074990626172, 7443.367313778282, 7445.181030086686, 7552.246214941673, 7742.341900938371, 4044.474218351654, 4108.383742438658, 4108.794560271975, 4109.219248342805, 4148.62662360389, 3462.3767721809813, 3559.911002224965, 3562.109472631842, 3563.6436356364156, 3572.4105522588484, 640.2956747577628, 650.3074225949443, 660.5112216959511, 662.5064327076359, 665.6405541007898, 484.5033840313636, 485.78139837073456, 528.9285946397264, 561.4666067236142, 567.0, 7866.7639970800965, 7873.921260787319, 7908.565028923349, 8017.398695097774, 8076.3942704297415, 507.47983317100267, 510.67827268820406, 573.8651416917021, 583.215575051555, 583.6177303865975, 3574.1779390739757, 3982.900427489336, 4138.8551400700835, 4298.591633794785, 4390.495093064274, 61416.0, 67698.32301677018, 69637.0, 69637.65181174062, 69734.60530789457, 71220.93168602475, 71366.0, 73529.63235183789, 73645.26354736384, 79850.402991941, 60670.449716271454, 61851.0, 62706.0, 74095.62952185313, 74717.62641186759, 8255.917440825755, 8340.41617086798, 8348.916510835057, 8450.95774521148, 8572.65709371643, 40064.99837504086, 40947.7952610235, 41121.38317925174, 42727.5727242736, 43806.0, 45140.614453915434, 51047.0, 57012.71493642505, 57113.71443142758, 57182.0, 10973.341599504056, 11067.335959842436, 11122.61070862516, 11157.0, 11302.660920172408, 11845.289282643069, 11926.761464770829, 12235.93882030584, 12323.0, 12764.29796361209, 9073.500957447404, 10850.457477125954, 10914.345139291669, 11407.372594507382, 11699.88300116988, 9514.905785834717, 11129.387883666475, 11155.330680158891, 11177.776444471228, 11640.126990475917, 10892.292001019909, 11058.557657693602, 11077.723056923642, 11089.33463992163, 11258.324500529994, 9268.443893366419, 9409.106134917172, 9417.199538039069, 9591.740030426328, 9723.558591120802, 9322.254219662274, 9421.557632398753, 9426.198773104363, 9511.952440237754, 9699.466529340954], "value": 6799.223718727822, "unit": "score"}], "name": "dromaeo_css", "value": 7069.20333249661, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 104.0, 136.0, 188.0, 138.0, 159.0, 135.0, 119.0, 166.0, 123.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 186.0, 187.0, 182.0, 184.0, 185.0, 195.0, 225.0], "value": 189.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 57.0, 108.0, 57.0, 61.0, 56.0, 59.0, 113.0, 55.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 100.0, 79.0, 80.0, 75.0, 109.0, 110.0, 110.0, 109.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 86.0, 83.0, 87.0, 83.0, 85.0, 99.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 103.0, 141.0, 94.0, 93.0, 99.0, 95.0, 93.0, 130.0, 93.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 130.0, 86.0, 82.0, 85.0, 69.0, 128.0, 131.0, 94.0, 80.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 58.0, 57.0, 57.0, 56.0, 57.0, 78.0, 59.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 75.0, 75.0, 70.0, 72.0, 69.0, 66.0, 70.0, 95.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 96.0, 104.0, 86.0, 86.0, 83.0, 81.0, 87.0, 84.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 135.0, 141.0, 134.0, 134.0, 140.0, 139.0, 138.0, 196.0, 148.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 187.0, 219.0, 193.0, 170.0, 221.0, 172.0, 165.0, 166.0, 167.0], "value": 182.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 65.0, 67.0, 66.0, 65.0, 65.0, 65.0, 67.0, 66.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1509.8000000000002, "alertThreshold": 2.0}]} 11:00:52 INFO - Return code: 0 11:00:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:00:53 ERROR - # TBPL SUCCESS # 11:00:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:00:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:00:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:00:53 INFO - 2016-05-07 11:00:53.019 screenresolution[1973:17565] starting screenresolution argv=screenresolution get 11:00:53 INFO - 2016-05-07 11:00:53.022 screenresolution[1973:17565] Display 0: 1600x1200x32@50 11:00:53 INFO - 2016-05-07 11:00:53.028 screenresolution[1974:17567] starting screenresolution argv=screenresolution list 11:00:53 INFO - Available Modes on Display 0 11:00:53 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 11:00:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:00:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:00:53 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 11:00:53 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 11:00:53 INFO - Graphics/Displays: 11:00:53 INFO - Intel Iris: 11:00:53 INFO - Chipset Model: Intel Iris 11:00:53 INFO - Type: GPU 11:00:53 INFO - Bus: Built-In 11:00:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:00:53 INFO - Vendor: Intel (0x8086) 11:00:53 INFO - Device ID: 0x0a2e 11:00:53 INFO - Revision ID: 0x0009 11:00:53 INFO - Displays: 11:00:53 INFO - Display: 11:00:53 INFO - Resolution: 1600 x 1200 @ 50 Hz 11:00:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:00:53 INFO - Display Serial Number: 1600x1200 50 11:00:53 INFO - Main Display: Yes 11:00:53 INFO - Mirror: Off 11:00:53 INFO - Online: Yes 11:00:53 INFO - Rotation: Supported 11:00:53 INFO - Return code: 0 11:00:53 INFO - Running post-action listener: _resource_record_post_action 11:00:53 INFO - ##### 11:00:53 INFO - ##### Finished run-tests step (success) 11:00:53 INFO - ##### 11:00:53 INFO - Running post-run listener: _resource_record_post_run 11:00:54 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1052672; Write bytes: 699719168; Read time: 13; Write time: 2140 11:00:54 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379859456; Write bytes: 374974464; Read time: 28151; Write time: 765 11:00:54 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1052672; Write bytes: 299931136; Read time: 13; Write time: 1322 11:00:54 INFO - Running post-run listener: _upload_blobber_files 11:00:54 INFO - Blob upload gear active. 11:00:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:00:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:00:54 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'] 11:00:54 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 11:00:54 INFO - (blobuploader) - INFO - Open directory for files ... 11:00:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:00:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:00:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:00:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:00:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:00:55 INFO - (blobuploader) - INFO - Done attempting. 11:00:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:00:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:00:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:00:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:00:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:00:56 INFO - (blobuploader) - INFO - Done attempting. 11:00:56 INFO - (blobuploader) - INFO - Iteration through files over. 11:00:56 INFO - Return code: 0 11:00:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:00:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:00:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ccf20f89f1567d9f37731c7ccc3804a0a1a5c2ac22caac5c6f1da892224afd0c84f4741bb810ca6cf9adebe97089b95d6120444c5d54cd7f5a1b6d25e5c1f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5a6073a2bb28c7c75c2080c9cf697d1820ad0993404aa3597a1926f4e7e268c09760ecfc6ce5c90ff620f8fe624bd48e846f0cca5de49260eab22c9183cb665"} 11:00:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:00:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:00:56 INFO - Contents: 11:00:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ccf20f89f1567d9f37731c7ccc3804a0a1a5c2ac22caac5c6f1da892224afd0c84f4741bb810ca6cf9adebe97089b95d6120444c5d54cd7f5a1b6d25e5c1f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5a6073a2bb28c7c75c2080c9cf697d1820ad0993404aa3597a1926f4e7e268c09760ecfc6ce5c90ff620f8fe624bd48e846f0cca5de49260eab22c9183cb665"} 11:00:56 INFO - Running post-run listener: copy_logs_to_upload_dir 11:00:56 INFO - Copying logs to upload dir... 11:00:56 INFO - mkdir: /builds/slave/test/build/upload/logs 11:00:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.649903 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-07 11:00:56.733733) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 11:00:56.736846) ========= 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.WsWaFfHXGG/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.9SomJDmK0N/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ccf20f89f1567d9f37731c7ccc3804a0a1a5c2ac22caac5c6f1da892224afd0c84f4741bb810ca6cf9adebe97089b95d6120444c5d54cd7f5a1b6d25e5c1f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5a6073a2bb28c7c75c2080c9cf697d1820ad0993404aa3597a1926f4e7e268c09760ecfc6ce5c90ff620f8fe624bd48e846f0cca5de49260eab22c9183cb665"} build_url:https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/U8t-es05RhGjy_BCGrJLIg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ccf20f89f1567d9f37731c7ccc3804a0a1a5c2ac22caac5c6f1da892224afd0c84f4741bb810ca6cf9adebe97089b95d6120444c5d54cd7f5a1b6d25e5c1f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f5a6073a2bb28c7c75c2080c9cf697d1820ad0993404aa3597a1926f4e7e268c09760ecfc6ce5c90ff620f8fe624bd48e846f0cca5de49260eab22c9183cb665"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 11:00:56.768752) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 11:00:56.769394) ========= 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.WsWaFfHXGG/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.9SomJDmK0N/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005136 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 11:00:56.821932) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 11:00:56.822588) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 11:00:56.823029) ========= ========= Total master_lag: 0.17 =========