builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0026 starttime: 1463496180.21 results: success (0) buildid: 20160517053732 builduid: d3f584bf73e040a4bd1c655c71273364 revision: c11343f547559a1fc96da1e5d36ca3db82cd84d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.209004) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.209508) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.209811) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.240546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.240913) ========= 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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-17 07:43:00-- 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% 13.0M=0.001s 2016-05-17 07:43:00 (13.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138775 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.397962) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.398278) ========= 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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.028014 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.451128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:00.453552) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c11343f547559a1fc96da1e5d36ca3db82cd84d5 --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 c11343f547559a1fc96da1e5d36ca3db82cd84d5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OaegpSLFUK/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.Af9ExCIDQ4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-17 07:43:00,528 truncating revision to first 12 chars 2016-05-17 07:43:00,528 Setting DEBUG logging. 2016-05-17 07:43:00,528 attempt 1/10 2016-05-17 07:43:00,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c11343f54755?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 07:43:01,200 unpacking tar archive at: fx-team-c11343f54755/testing/mozharness/ program finished with exit code 0 elapsedTime=0.915232 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:01.386075) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:01.389494) ========= script_repo_revision: c11343f547559a1fc96da1e5d36ca3db82cd84d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:01.389964) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:01.390229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 07:43:01.404282) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-17 07:43:01.404683) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OaegpSLFUK/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.Af9ExCIDQ4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:43:01 INFO - MultiFileLogger online at 20160517 07:43:01 in /builds/slave/test 07:43:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:43:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:43:01 INFO - {'append_to_log': False, 07:43:01 INFO - 'base_work_dir': '/builds/slave/test', 07:43:01 INFO - 'blob_upload_branch': 'Fx-Team', 07:43:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:43:01 INFO - 'branch': 'Fx-Team', 07:43:01 INFO - 'buildbot_json_path': 'buildprops.json', 07:43:01 INFO - 'config_files': ('talos/mac_config.py',), 07:43:01 INFO - 'default_actions': ('clobber', 07:43:01 INFO - 'read-buildbot-config', 07:43:01 INFO - 'download-and-extract', 07:43:01 INFO - 'populate-webroot', 07:43:01 INFO - 'create-virtualenv', 07:43:01 INFO - 'install', 07:43:01 INFO - 'run-tests'), 07:43:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:43:01 INFO - 'download_minidump_stackwalk': True, 07:43:01 INFO - 'download_symbols': 'ondemand', 07:43:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:43:01 INFO - 'tooltool.py': '/tools/tooltool.py', 07:43:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:43:01 INFO - '/tools/misc-python/virtualenv.py')}, 07:43:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:43:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:43:01 INFO - 'installer_path': 'installer.exe', 07:43:01 INFO - 'log_level': 'info', 07:43:01 INFO - 'log_name': 'talos', 07:43:01 INFO - 'log_to_console': True, 07:43:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:43:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:43:01 INFO - 'opt_config_files': (), 07:43:01 INFO - 'pip_index': False, 07:43:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:43:01 INFO - 'cmd': ('bash', 07:43:01 INFO - '-c', 07:43:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:43:01 INFO - 'enabled': True, 07:43:01 INFO - 'halt_on_failure': False, 07:43:01 INFO - 'name': 'check_screen_resolution'},), 07:43:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:43:01 INFO - 'cmd': ('bash', 07:43:01 INFO - '-c', 07:43:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:43:01 INFO - 'enabled': True, 07:43:01 INFO - 'halt_on_failure': False, 07:43:01 INFO - 'name': 'check_screen_resolution'},), 07:43:01 INFO - 'run_cmd_checks_enabled': True, 07:43:01 INFO - 'sps_profile': False, 07:43:01 INFO - 'sps_profile_interval': 0, 07:43:01 INFO - 'suite': 'dromaeojs-e10s', 07:43:01 INFO - 'system_bits': '32', 07:43:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:43:01 INFO - 'title': 't-yosemite-r7-0026', 07:43:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:43:01 INFO - 'use_talos_json': True, 07:43:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:43:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:43:01 INFO - 'work_dir': 'build'} 07:43:01 INFO - ##### 07:43:01 INFO - ##### Running clobber step. 07:43:01 INFO - ##### 07:43:01 INFO - Running pre-action listener: _resource_record_pre_action 07:43:01 INFO - Running main action method: clobber 07:43:01 INFO - rmtree: /builds/slave/test/build 07:43:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:43:03 INFO - Running post-action listener: _resource_record_post_action 07:43:03 INFO - ##### 07:43:03 INFO - ##### Finished clobber step (success) 07:43:03 INFO - ##### 07:43:03 INFO - ##### 07:43:03 INFO - ##### Running read-buildbot-config step. 07:43:03 INFO - ##### 07:43:03 INFO - Running pre-action listener: _resource_record_pre_action 07:43:03 INFO - Running main action method: read_buildbot_config 07:43:03 INFO - Using buildbot properties: 07:43:03 INFO - { 07:43:03 INFO - "project": "", 07:43:03 INFO - "product": "firefox", 07:43:03 INFO - "script_repo_revision": "production", 07:43:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 07:43:03 INFO - "repository": "", 07:43:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 07:43:03 INFO - "buildid": "20160517053732", 07:43:03 INFO - "pgo_build": "False", 07:43:03 INFO - "basedir": "/builds/slave/test", 07:43:03 INFO - "buildnumber": 1, 07:43:03 INFO - "slavename": "t-yosemite-r7-0026", 07:43:03 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:43:03 INFO - "platform": "yosemite_r7", 07:43:03 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 07:43:03 INFO - "branch": "fx-team", 07:43:03 INFO - "repo_path": "integration/fx-team", 07:43:03 INFO - "revision": "c11343f547559a1fc96da1e5d36ca3db82cd84d5", 07:43:03 INFO - "stage_platform": "macosx64", 07:43:03 INFO - "builduid": "d3f584bf73e040a4bd1c655c71273364", 07:43:03 INFO - "slavebuilddir": "test" 07:43:03 INFO - } 07:43:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:43:03 INFO - Running post-action listener: _resource_record_post_action 07:43:03 INFO - ##### 07:43:03 INFO - ##### Finished read-buildbot-config step (success) 07:43:03 INFO - ##### 07:43:03 INFO - ##### 07:43:03 INFO - ##### Running download-and-extract step. 07:43:03 INFO - ##### 07:43:03 INFO - Running pre-action listener: _resource_record_pre_action 07:43:03 INFO - Running main action method: download_and_extract 07:43:03 INFO - mkdir: /builds/slave/test/build/tests 07:43: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')]} 07:43:03 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:43:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:43:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:43:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:04 INFO - Downloaded 1199 bytes. 07:43:04 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:43:04 INFO - Using the following test package requirements: 07:43:04 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:43:04 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:43:04 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:43:04 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'jsshell-mac.zip'], 07:43:04 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:43:04 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:43:04 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:43:04 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:43:04 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:43:04 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:43:04 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:43:04 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:04 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:43:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:43:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:43:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:06 INFO - Downloaded 18267719 bytes. 07:43:06 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'] 07:43:06 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 07:43:09 INFO - Return code: 0 07:43:09 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 07:43:09 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')]} 07:43:09 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:43:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:43:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:43:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:10 INFO - Downloaded 18267719 bytes. 07:43:10 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'] 07:43:10 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 07:43:14 INFO - Return code: 0 07:43:14 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')]} 07:43:14 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:43:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:43:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:43:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:15 INFO - Downloaded 10840194 bytes. 07:43:15 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'] 07:43:15 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 07:43:15 INFO - Return code: 0 07:43: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')]} 07:43:15 INFO - https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:43:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:43:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ig7ggYxRSqOT4E8oVCTflA/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 07:43:18 INFO - Downloaded 109961212 bytes. 07:43:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:19 INFO - mkdir: /builds/slave/test/properties 07:43:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:43:19 INFO - Writing to file /builds/slave/test/properties/build_url 07:43:19 INFO - Contents: 07:43:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:20 INFO - Running post-action listener: _resource_record_post_action 07:43:20 INFO - Running post-action listener: set_extra_try_arguments 07:43:20 INFO - ##### 07:43:20 INFO - ##### Finished download-and-extract step (success) 07:43:20 INFO - ##### 07:43:20 INFO - ##### 07:43:20 INFO - ##### Running populate-webroot step. 07:43:20 INFO - ##### 07:43:20 INFO - Running pre-action listener: _resource_record_pre_action 07:43:20 INFO - Running main action method: populate_webroot 07:43:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:43:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:43:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:43:20 INFO - '--tppagecycles', 07:43:20 INFO - '7'], 07:43:20 INFO - 'tests': ['tsvgm']}}, 07:43:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:43:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:43:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:43:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:43:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['damp', 'tps']}, 07:43:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'tests': ['damp', 'tps']}, 07:43:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['dromaeo_dom']}, 07:43:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:43:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['a11yr', 07:43:20 INFO - 'ts_paint', 07:43:20 INFO - 'tpaint', 07:43:20 INFO - 'sessionrestore', 07:43:20 INFO - 'sessionrestore_no_auto_restore', 07:43:20 INFO - 'tabpaint']}, 07:43:20 INFO - 'other-e10s': {'tests': ['a11yr', 07:43:20 INFO - 'ts_paint', 07:43:20 INFO - 'tpaint', 07:43:20 INFO - 'sessionrestore', 07:43:20 INFO - 'sessionrestore_no_auto_restore', 07:43:20 INFO - 'tabpaint']}, 07:43:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:43:20 INFO - 'ts_paint', 07:43:20 INFO - 'tpaint', 07:43:20 INFO - 'sessionrestore', 07:43:20 INFO - 'sessionrestore_no_auto_restore', 07:43:20 INFO - 'tabpaint']}, 07:43:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:43:20 INFO - 'ts_paint', 07:43:20 INFO - 'tpaint', 07:43:20 INFO - 'sessionrestore', 07:43:20 INFO - 'sessionrestore_no_auto_restore', 07:43:20 INFO - 'tabpaint']}, 07:43:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['a11yr', 07:43:20 INFO - 'ts_paint', 07:43:20 INFO - 'tpaint', 07:43:20 INFO - 'sessionrestore', 07:43:20 INFO - 'sessionrestore_no_auto_restore', 07:43:20 INFO - 'tabpaint']}, 07:43:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['a11yr', 07:43:20 INFO - 'ts_paint', 07:43:20 INFO - 'tpaint', 07:43:20 INFO - 'sessionrestore', 07:43:20 INFO - 'sessionrestore_no_auto_restore', 07:43:20 INFO - 'tabpaint']}, 07:43:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['tsvgx', 07:43:20 INFO - 'tsvgr_opacity', 07:43:20 INFO - 'tart', 07:43:20 INFO - 'tscrollx', 07:43:20 INFO - 'cart']}, 07:43:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:43:20 INFO - 'tsvgr_opacity', 07:43:20 INFO - 'tart', 07:43:20 INFO - 'tscrollx', 07:43:20 INFO - 'cart']}, 07:43:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'talos_options': ['--disable-e10s'], 07:43:20 INFO - 'tests': ['tp5o']}, 07:43:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'tests': ['tp5o']}, 07:43:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'talos_options': ['--disable-e10s', 07:43:20 INFO - '--xperf_path', 07:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:20 INFO - 'tests': ['tp5n']}, 07:43:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:20 INFO - 'talos_options': ['--xperf_path', 07:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:20 INFO - 'tests': ['tp5n']}}, 07:43:20 INFO - 'talos.zip': {'path': '', 07:43:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:43:20 INFO - Running post-action listener: _resource_record_post_action 07:43:20 INFO - ##### 07:43:20 INFO - ##### Finished populate-webroot step (success) 07:43:20 INFO - ##### 07:43:20 INFO - ##### 07:43:20 INFO - ##### Running create-virtualenv step. 07:43:20 INFO - ##### 07:43:20 INFO - Running pre-action listener: _resource_record_pre_action 07:43:20 INFO - Running main action method: create_virtualenv 07:43:20 INFO - Creating virtualenv /builds/slave/test/build/venv 07:43:20 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 07:43:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:43:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:43:20 INFO - Using real prefix '/tools/python27' 07:43:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:43:21 INFO - Installing distribute.............................................................................................................................................................................................done. 07:43:24 INFO - Installing pip.................done. 07:43:24 INFO - Return code: 0 07:43:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:24 INFO - 'HOME': '/Users/cltbld', 07:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:24 INFO - 'LOGNAME': 'cltbld', 07:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:24 INFO - 'MOZ_NO_REMOTE': '1', 07:43:24 INFO - 'NO_EM_RESTART': '1', 07:43:24 INFO - 'PAGER': '/bin/cat', 07:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:24 INFO - 'PWD': '/builds/slave/test', 07:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:24 INFO - 'SHELL': '/bin/bash', 07:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:24 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:24 INFO - 'USER': 'cltbld', 07:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:24 INFO - 'XPC_FLAGS': '0x0', 07:43:24 INFO - 'XPC_SERVICE_NAME': '0', 07:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:24 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 07:43:24 INFO - Cleaning up... 07:43:24 INFO - Return code: 0 07:43:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:24 INFO - 'HOME': '/Users/cltbld', 07:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:24 INFO - 'LOGNAME': 'cltbld', 07:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:24 INFO - 'MOZ_NO_REMOTE': '1', 07:43:24 INFO - 'NO_EM_RESTART': '1', 07:43:24 INFO - 'PAGER': '/bin/cat', 07:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:24 INFO - 'PWD': '/builds/slave/test', 07:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:24 INFO - 'SHELL': '/bin/bash', 07:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:24 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:24 INFO - 'USER': 'cltbld', 07:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:24 INFO - 'XPC_FLAGS': '0x0', 07:43:24 INFO - 'XPC_SERVICE_NAME': '0', 07:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:24 INFO - Downloading/unpacking psutil>=3.1.1 07:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:43:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:27 INFO - Installing collected packages: psutil 07:43:27 INFO - Running setup.py install for psutil 07:43:27 INFO - building 'psutil._psutil_osx' extension 07:43:27 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 07:43:27 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 07:43:27 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 07:43:28 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 07:43:28 INFO - building 'psutil._psutil_posix' extension 07:43:28 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 07:43:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:43:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:43:28 INFO - ^ 07:43:28 INFO - 1 warning generated. 07:43:28 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 07:43:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:28 INFO - Successfully installed psutil 07:43:28 INFO - Cleaning up... 07:43:28 INFO - Return code: 0 07:43:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:43: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')]} 07:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43: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')]} 07:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:43:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:28 INFO - 'HOME': '/Users/cltbld', 07:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:28 INFO - 'LOGNAME': 'cltbld', 07:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:28 INFO - 'MOZ_NO_REMOTE': '1', 07:43:28 INFO - 'NO_EM_RESTART': '1', 07:43:28 INFO - 'PAGER': '/bin/cat', 07:43:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:28 INFO - 'PWD': '/builds/slave/test', 07:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:28 INFO - 'SHELL': '/bin/bash', 07:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:28 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:28 INFO - 'USER': 'cltbld', 07:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:28 INFO - 'XPC_FLAGS': '0x0', 07:43:28 INFO - 'XPC_SERVICE_NAME': '0', 07:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:43:31 INFO - Installing collected packages: mozsystemmonitor 07:43:31 INFO - Running setup.py install for mozsystemmonitor 07:43:31 INFO - Successfully installed mozsystemmonitor 07:43:31 INFO - Cleaning up... 07:43:31 INFO - Return code: 0 07:43:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:43:31 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')]} 07:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:31 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')]} 07:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:31 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 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:31 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 07:43:31 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 07:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:31 INFO - 'HOME': '/Users/cltbld', 07:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:31 INFO - 'LOGNAME': 'cltbld', 07:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:31 INFO - 'MOZ_NO_REMOTE': '1', 07:43:31 INFO - 'NO_EM_RESTART': '1', 07:43:31 INFO - 'PAGER': '/bin/cat', 07:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:31 INFO - 'PWD': '/builds/slave/test', 07:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:31 INFO - 'SHELL': '/bin/bash', 07:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:31 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:31 INFO - 'USER': 'cltbld', 07:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:31 INFO - 'XPC_FLAGS': '0x0', 07:43:31 INFO - 'XPC_SERVICE_NAME': '0', 07:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:31 INFO - Downloading/unpacking blobuploader==1.2.4 07:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:34 INFO - Downloading blobuploader-1.2.4.tar.gz 07:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:43:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:43:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43:35 INFO - Downloading docopt-0.6.1.tar.gz 07:43:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:43:35 INFO - Installing collected packages: blobuploader, requests, docopt 07:43:35 INFO - Running setup.py install for blobuploader 07:43:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:43:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:43:35 INFO - Running setup.py install for requests 07:43:36 INFO - Running setup.py install for docopt 07:43:36 INFO - Successfully installed blobuploader requests docopt 07:43:36 INFO - Cleaning up... 07:43:36 INFO - Return code: 0 07:43:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:36 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 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:36 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 07:43:36 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 07:43:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:36 INFO - 'HOME': '/Users/cltbld', 07:43:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:36 INFO - 'LOGNAME': 'cltbld', 07:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:36 INFO - 'MOZ_NO_REMOTE': '1', 07:43:36 INFO - 'NO_EM_RESTART': '1', 07:43:36 INFO - 'PAGER': '/bin/cat', 07:43:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:36 INFO - 'PWD': '/builds/slave/test', 07:43:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:36 INFO - 'SHELL': '/bin/bash', 07:43:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:36 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:36 INFO - 'USER': 'cltbld', 07:43:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:36 INFO - 'XPC_FLAGS': '0x0', 07:43:36 INFO - 'XPC_SERVICE_NAME': '0', 07:43:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:36 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-0yRg2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:36 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-FnUwQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:36 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-KI6nHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:36 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-EFJBFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-B1nZnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-z07eXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-tVQF04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-tzkVTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-yIu3LO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-5sNei2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Xa_8hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-zESCsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-iTvhYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:37 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oCWPf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:38 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Dhr7r8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:38 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-3nzB3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:38 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-OgkTED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:43:38 INFO - Running setup.py install for manifestparser 07:43:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:43:38 INFO - Running setup.py install for mozcrash 07:43:38 INFO - Running setup.py install for mozdebug 07:43:38 INFO - Running setup.py install for mozdevice 07:43:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:43:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:43:38 INFO - Running setup.py install for mozfile 07:43:39 INFO - Running setup.py install for mozhttpd 07:43:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozinfo 07:43:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozInstall 07:43:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozleak 07:43:39 INFO - Running setup.py install for mozlog 07:43:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for moznetwork 07:43:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozprocess 07:43:40 INFO - Running setup.py install for mozprofile 07:43:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Running setup.py install for mozrunner 07:43:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Running setup.py install for mozscreenshot 07:43:40 INFO - Running setup.py install for moztest 07:43:40 INFO - Running setup.py install for mozversion 07:43:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:43:40 INFO - Cleaning up... 07:43:40 INFO - Return code: 0 07:43:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:40 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')]} 07:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:40 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')]} 07:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:40 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 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:40 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 07:43:40 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 07:43:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:40 INFO - 'HOME': '/Users/cltbld', 07:43:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:40 INFO - 'LOGNAME': 'cltbld', 07:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:40 INFO - 'MOZ_NO_REMOTE': '1', 07:43:40 INFO - 'NO_EM_RESTART': '1', 07:43:40 INFO - 'PAGER': '/bin/cat', 07:43:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:40 INFO - 'PWD': '/builds/slave/test', 07:43:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:40 INFO - 'SHELL': '/bin/bash', 07:43:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:40 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:40 INFO - 'USER': 'cltbld', 07:43:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:40 INFO - 'XPC_FLAGS': '0x0', 07:43:40 INFO - 'XPC_SERVICE_NAME': '0', 07:43:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-jnFjbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-_uT6wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-sMXZYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-JjeWrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-fq4D30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-mOzmht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ligHQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:41 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-qzTlU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-8r31M7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xMpKOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-GSVK9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oSukwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xtdugo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-br4kW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-pvtm9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-osSaZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:42 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-c8reWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:43 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)) 07:43:43 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)) 07:43:43 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)) 07:43:43 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)) 07:43:43 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)) 07:43:43 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)) 07:43:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:43:43 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 07:43:43 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 07:43:43 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 07:43:43 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 07:43:43 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 07:43:43 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 07:43:45 INFO - Downloading blessings-1.6.tar.gz 07:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:43:45 INFO - Installing collected packages: blessings 07:43:45 INFO - Running setup.py install for blessings 07:43:45 INFO - Successfully installed blessings 07:43:45 INFO - Cleaning up... 07:43:45 INFO - Return code: 0 07:43:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:43:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:46 INFO - Reading from file tmpfile_stdout 07:43:46 INFO - Current package versions: 07:43:46 INFO - blessings == 1.6 07:43:46 INFO - blobuploader == 1.2.4 07:43:46 INFO - docopt == 0.6.1 07:43:46 INFO - manifestparser == 1.1 07:43:46 INFO - mozInstall == 1.12 07:43:46 INFO - mozcrash == 0.17 07:43:46 INFO - mozdebug == 0.1 07:43:46 INFO - mozdevice == 0.48 07:43:46 INFO - mozfile == 1.2 07:43:46 INFO - mozhttpd == 0.7 07:43:46 INFO - mozinfo == 0.9 07:43:46 INFO - mozleak == 0.1 07:43:46 INFO - mozlog == 3.2 07:43:46 INFO - moznetwork == 0.27 07:43:46 INFO - mozprocess == 0.22 07:43:46 INFO - mozprofile == 0.28 07:43:46 INFO - mozrunner == 6.11 07:43:46 INFO - mozscreenshot == 0.1 07:43:46 INFO - mozsystemmonitor == 0.3 07:43:46 INFO - moztest == 0.7 07:43:46 INFO - mozversion == 1.4 07:43:46 INFO - psutil == 3.1.1 07:43:46 INFO - requests == 1.2.3 07:43:46 INFO - wsgiref == 0.1.2 07:43:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:46 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 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:46 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 07:43:46 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 07:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:46 INFO - 'HOME': '/Users/cltbld', 07:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:46 INFO - 'LOGNAME': 'cltbld', 07:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:46 INFO - 'MOZ_NO_REMOTE': '1', 07:43:46 INFO - 'NO_EM_RESTART': '1', 07:43:46 INFO - 'PAGER': '/bin/cat', 07:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:46 INFO - 'PWD': '/builds/slave/test', 07:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:46 INFO - 'SHELL': '/bin/bash', 07:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:46 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:46 INFO - 'USER': 'cltbld', 07:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:46 INFO - 'XPC_FLAGS': '0x0', 07:43:46 INFO - 'XPC_SERVICE_NAME': '0', 07:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 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)) 07:43:46 INFO - Cleaning up... 07:43:46 INFO - Return code: 0 07:43:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:46 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 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:46 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 07:43:46 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 07:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:46 INFO - 'HOME': '/Users/cltbld', 07:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:46 INFO - 'LOGNAME': 'cltbld', 07:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:46 INFO - 'MOZ_NO_REMOTE': '1', 07:43:46 INFO - 'NO_EM_RESTART': '1', 07:43:46 INFO - 'PAGER': '/bin/cat', 07:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:46 INFO - 'PWD': '/builds/slave/test', 07:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:46 INFO - 'SHELL': '/bin/bash', 07:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:46 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:46 INFO - 'USER': 'cltbld', 07:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:46 INFO - 'XPC_FLAGS': '0x0', 07:43:46 INFO - 'XPC_SERVICE_NAME': '0', 07:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:46 INFO - Downloading/unpacking jsonschema 07:43:46 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 07:43:46 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 07:43:46 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 07:43:46 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 07:43:46 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 07:43:46 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 07:43:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:43:49 INFO - Downloading/unpacking functools32 (from jsonschema) 07:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:49 INFO - Downloading functools32-3.2.3-2.tar.gz 07:43:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:43:49 INFO - warning: no files found matching '*.txt' 07:43:49 INFO - no previously-included directories found matching 'build' 07:43:49 INFO - no previously-included directories found matching 'dist' 07:43:49 INFO - no previously-included directories found matching '.git*' 07:43:49 INFO - Installing collected packages: jsonschema, functools32 07:43:49 INFO - Running setup.py install for functools32 07:43:49 INFO - warning: no files found matching '*.txt' 07:43:49 INFO - no previously-included directories found matching 'build' 07:43:49 INFO - no previously-included directories found matching 'dist' 07:43:49 INFO - no previously-included directories found matching '.git*' 07:43:49 INFO - Successfully installed jsonschema functools32 07:43:49 INFO - Cleaning up... 07:43:49 INFO - Return code: 0 07:43:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:50 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')]} 07:43:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:50 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 0x107efda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0fadce940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, '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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:43:50 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 07:43:50 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 07:43:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:43:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:50 INFO - 'HOME': '/Users/cltbld', 07:43:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:50 INFO - 'LOGNAME': 'cltbld', 07:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:50 INFO - 'MOZ_NO_REMOTE': '1', 07:43:50 INFO - 'NO_EM_RESTART': '1', 07:43:50 INFO - 'PAGER': '/bin/cat', 07:43:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:50 INFO - 'PWD': '/builds/slave/test', 07:43:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:50 INFO - 'SHELL': '/bin/bash', 07:43:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:43:50 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:43:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:50 INFO - 'USER': 'cltbld', 07:43:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:50 INFO - 'XPC_FLAGS': '0x0', 07:43:50 INFO - 'XPC_SERVICE_NAME': '0', 07:43:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:50 INFO - Downloading/unpacking flake8 07:43:50 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 07:43:50 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 07:43:50 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 07:43:50 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 07:43:50 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 07:43:50 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 07:43:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:43:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:43:53 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 07:43:53 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 07:43:53 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 07:43:53 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 07:43:53 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 07:43:53 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 07:43:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:43:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:43:53 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 07:43:53 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 07:43:53 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 07:43:53 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 07:43:53 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 07:43:53 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 07:43:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:43:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:43:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:43:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:43:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:43:54 INFO - no previously-included directories found matching 'docs/_build' 07:43:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:43:54 INFO - Running setup.py install for pep8 07:43:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:43:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:43:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:43:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:43:54 INFO - no previously-included directories found matching 'docs/_build' 07:43:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:43:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:43:54 INFO - Cleaning up... 07:43:55 INFO - Return code: 0 07:43:55 INFO - Running post-action listener: _resource_record_post_action 07:43:55 INFO - Running post-action listener: _start_resource_monitoring 07:43:55 INFO - Starting resource monitoring. 07:43:55 INFO - ##### 07:43:55 INFO - ##### Finished create-virtualenv step (success) 07:43:55 INFO - ##### 07:43:55 INFO - ##### 07:43:55 INFO - ##### Running install step. 07:43:55 INFO - ##### 07:43:55 INFO - Running pre-action listener: _resource_record_pre_action 07:43:55 INFO - Running main action method: install 07:43:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:55 INFO - Reading from file tmpfile_stdout 07:43:55 INFO - Detecting whether we're running mozinstall >=1.0... 07:43:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:43:55 INFO - Reading from file tmpfile_stdout 07:43:55 INFO - Output received: 07:43:55 INFO - Usage: mozinstall [options] installer 07:43:55 INFO - Options: 07:43:55 INFO - -h, --help show this help message and exit 07:43:55 INFO - -d DEST, --destination=DEST 07:43:55 INFO - Directory to install application into. [default: 07:43:55 INFO - "/builds/slave/test"] 07:43:55 INFO - --app=APP Application being installed. [default: firefox] 07:43:55 INFO - mkdir: /builds/slave/test/build/application 07:43:55 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'] 07:43:55 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 07:44:29 INFO - Reading from file tmpfile_stdout 07:44:29 INFO - Output received: 07:44:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:44:29 INFO - Running post-action listener: _resource_record_post_action 07:44:29 INFO - ##### 07:44:29 INFO - ##### Finished install step (success) 07:44:29 INFO - ##### 07:44:29 INFO - ##### 07:44:29 INFO - ##### Running run-tests step. 07:44:29 INFO - ##### 07:44:29 INFO - Running pre-action listener: _resource_record_pre_action 07:44:29 INFO - Running main action method: run_tests 07:44:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:44:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:44:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:44:29 INFO - 2016-05-17 07:44:29.085 screenresolution[1643:7675] starting screenresolution argv=screenresolution get 07:44:29 INFO - 2016-05-17 07:44:29.088 screenresolution[1643:7675] Display 0: 1600x1200x32@60 07:44:29 INFO - 2016-05-17 07:44:29.094 screenresolution[1644:7677] starting screenresolution argv=screenresolution list 07:44:29 INFO - Available Modes on Display 0 07:44:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:44:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:44:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:44:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:44:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:44:29 INFO - Intel Iris: 07:44:29 INFO - Chipset Model: Intel Iris 07:44:29 INFO - Type: GPU 07:44:29 INFO - Bus: Built-In 07:44:29 INFO - VRAM (Dynamic, Max): 1536 MB 07:44:29 INFO - Vendor: Intel (0x8086) 07:44:29 INFO - Device ID: 0x0a2e 07:44:29 INFO - Revision ID: 0x0009 07:44:29 INFO - Displays: 07:44:29 INFO - Display: 07:44:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:44:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:44:29 INFO - Display Serial Number: 1600x1200 60 07:44:29 INFO - Main Display: Yes 07:44:29 INFO - Mirror: Off 07:44:29 INFO - Online: Yes 07:44:29 INFO - Rotation: Supported 07:44:29 INFO - Return code: 0 07:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:44:29 INFO - Python 2.7.3 07:44:29 INFO - Return code: 0 07:44:29 INFO - grabbing minidump binary from tooltool 07:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:29 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107da1d30>, '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 0x107bc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:44:29 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 07:44:29 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 07:44:29 INFO - Calling ['/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'] with output_timeout 600 07:44:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:44:29 INFO - Return code: 0 07:44:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:44:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:44:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:44:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:44:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos Process Process-1: Traceback (most recent call last): File "/tools/python27/lib/python2.7/multiprocessing/process.py", line 258, in _bootstrap self.run() File "/tools/python27/lib/python2.7/multiprocessing/process.py", line 114, in run self._target(*self._args, **self._kwargs) File "/builds/slave/test/build/venv/lib/python2.7/site-packages/mozsystemmonitor/resourcemonitor.py", line 100, in _collect io_diff = [v - io_last[i] for i, v in enumerate(io)] IndexError: list index out of range 07:44:30 INFO - Return code: 0 07:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0026 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OaegpSLFUK/Render', 07:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:30 INFO - 'HOME': '/Users/cltbld', 07:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:30 INFO - 'LOGNAME': 'cltbld', 07:44:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:44:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:30 INFO - 'MOZ_NO_REMOTE': '1', 07:44:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:44:30 INFO - 'NO_EM_RESTART': '1', 07:44:30 INFO - 'PAGER': '/bin/cat', 07:44:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:30 INFO - 'PWD': '/builds/slave/test', 07:44:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:30 INFO - 'SHELL': '/bin/bash', 07:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Af9ExCIDQ4/Listeners', 07:44:30 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 07:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:30 INFO - 'USER': 'cltbld', 07:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:30 INFO - 'XPC_FLAGS': '0x0', 07:44:30 INFO - 'XPC_SERVICE_NAME': '0', 07:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:44:30 INFO - mozversion application_buildid: 20160517053732 07:44:30 INFO - mozversion application_changeset: c11343f547559a1fc96da1e5d36ca3db82cd84d5 07:44:30 INFO - mozversion application_display_name: Nightly 07:44:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:44:30 INFO - mozversion application_name: Firefox 07:44:30 INFO - mozversion application_remotingname: firefox 07:44:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:44:30 INFO - mozversion application_vendor: Mozilla 07:44:30 INFO - mozversion application_version: 49.0a1 07:44:30 INFO - mozversion platform_buildid: 20160517053732 07:44:30 INFO - mozversion platform_changeset: c11343f547559a1fc96da1e5d36ca3db82cd84d5 07:44:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:44:30 INFO - mozversion platform_version: 49.0a1 07:44:30 INFO - using testdate: 1463496270 07:44:30 INFO - actual date: 1463496270 07:44:30 INFO - starting webserver on 'localhost:49277' 07:44:30 INFO - SUITE-START | Running 2 tests 07:44:30 INFO - TEST-START | dromaeo_css 07:44:30 INFO - operating with platform_type : mac_ 07:44:30 INFO - Initialising browser for dromaeo_css test... 07:44:30 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpjM1UAU/profile http://localhost:49277/getInfo.html) 07:44:33 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 07:44:33 INFO - PROCESS | 1662 | colorDepth:24 07:44:33 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 07:44:33 INFO - PROCESS | 1662 | __metrics 07:44:33 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 07:44:34 INFO - TEST-INFO | 1662: exit 0 07:44:34 INFO - Browser initialized. 07:44:34 INFO - Running cycle 1/1 for dromaeo_css test... 07:44:34 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpjM1UAU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:46 INFO - PROCESS | 1665 | RSS: Main: 249995264 07:44:46 INFO - PROCESS | 1665 | 07:45:35 INFO - PROCESS | 1665 | 1463496335962 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] 07:47:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 07:47:48 INFO - PROCESS | 1665 | RSS: Main: 263606272 07:47:48 INFO - PROCESS | 1665 | 07:50:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 07:50:51 INFO - PROCESS | 1665 | RSS: Main: 275546112 07:50:51 INFO - PROCESS | 1665 | 07:53:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 07:53:54 INFO - PROCESS | 1665 | RSS: Main: 277557248 07:53:54 INFO - PROCESS | 1665 | 07:56:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 07:56:56 INFO - PROCESS | 1665 | RSS: Main: 279601152 07:56:56 INFO - PROCESS | 1665 | 07:59:59 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 07:59:59 INFO - PROCESS | 1665 | RSS: Main: 281993216 07:59:59 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:03:02 INFO - PROCESS | 1665 | RSS: Main: 283115520 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | __start_tp_report 08:03:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 08:03:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 08:03:02 INFO - PROCESS | 1665 | |i|pagename|runs| 08:03:02 INFO - PROCESS | 1665 | |0;dojo.html;3496.842642081106;3531.805750683712;3548.2903419316144;3558.199405133845;3574.6067932527417;5944.197533332998;5961.672108034062;6005.819825405234;6044.758209671608;6053.364396738345;5863.76544938202;5953.285605727308;5971.134185543094;5979.282486101674;5990.4309090636425;5267.86830329242;5303.681779093247;5402.162664786964;5413.458654134594;5503.257060296849;385.0555884117415;5303.813576172102;5411.107167317407;5418.051840927842;5492.450754924515;5360.088784906537;5363.758630861621;5379.35447746271;5411.9717253721865;5427.972860135714;5217.686938783685;5419.241306217133;5435.945640543583;5443.673379597237;5460.099083651193;5245;5254.710990895499;5281.683099014072;5317.308749862493;5325.946740532622;5798.130280457923;5909.290885093773;5981.192539007222;5992.520598352121;6006.399360064002;6177.907331390033;6184.134221209034;6253.7185826637915;6296.590721603127;6297.6536290504;6245.687715614201;6364.172657554534;6366.93633063668;6391.392817682313;6392.776252831129;6129.846753831145;6148.8770224595255;6164.87670246597;6187.041008643668;6208.4722798562625;6256.74973001084;6305.211848518935;6312.305646378986;6476.222853257639;6480.967595161994;6215.96892015537;6221.160143380676;6222.097795819581;6241.968790156111;6244.344343843922;5374.70439125844;5816.534677225812;6282.465990390868;6293.1504246927;6337.904931426032;4702.553257440538;6171.84570385735;6179.5056395488255;6201.81394558164;6214.875702486015;6157.507399408037;6168.19813424252;6208;6209.161763161916;6227.626342419469;5085.186370180753;5140.3831540215415;5146.382434107931;5148.9485105149215;5158.09733296679;4534.138513682456;4538.069695712371;4574.153781550423;4590.173768721662;4667.13657973276;6034.607750496291;6105.32841387447;6111.755529778848;6125.142480052797;6146.323904370515;6323;6341.460975817107;6348;6349.079383489368;6355.046742988588;5963.493103086286;6046.3651316612;6085.513158947274;6122.7857025004805;6129.080637904261;5625.156226566048;5637.49262566371;5638.971805140948;5644.802431914945;5650.208970744099;6298.433141017331;6530.869382612226;6553.4101930826455;6562.114114594564;6574.868502630016;6220.564560480723;6233.750649974132;6282.277538083179;6358.2687990879795;6360.39576240275;5159.251908473176;6520.413162815369;6533.314002029719;6539.313372095861;6606.306337834651;6182.876342473035;6197.287311959182;6270.905936410866;6272.717727702356;6284.591501552567;6190.783322583688;6323.747050117944;6333.303336633059;6340.4927605792345;6362.77730279438;5474.206240095244;5960.314563825043;6190.181847184509;6253.718582663882;6256.843578910563;6136.58034916419;6173.135760993375;6243.656598886923;6268.247810262797;6280.842978925563;6035.482345915514;6074.635521868702;6160.936086411102;6199.721012554536;6224.688765561795;6195.19462469876;6250.531210159165;6260.874782504416;6262.812115636538;6268.4671802897265;5503.422140675331;6129.053167990421;6140.0134868502855;6284.528660350401;6291.905621415591 08:03:02 INFO - PROCESS | 1665 | |1;ext.html;492.75116066386494;532.2521856791205;550.6420826462798;555.2088274209253;571.5341996273037;28749.85625071874;42762.572374276286;42790.144197116075;42830.7858460708;42916.785416072875;43425.131497370036;43818.90452738683;43830.780846095724;43964.90087747808;44012.89967750808;8434.53610051447;8466.195711407407;9513.5243237838;9713.028697130267;9766.218702503782;9641.662541811038;9664.130228279453;9755.707328780149;9764.267679923998;9857.950710246474;9753.268504862128;9758.707238782848;9786.070323319273;9788.119069283763;9802.36284641496;9727.221822254238;9782.951085244527;9789.21686265097;9798.657047003391;9886.752831179278;9646;9668.661596844147;9694.127528522467;9695.078967498077;9699.90300096997;39696.61061862821;40047.59952400497;40087.39868901969;40088;40095.7995210025;3376.8142752148624;3431.6396778338412;3504.4743288506675;3586.4799604057266;3860.4595356650093;29578.852105739334;29634.25914352137;30301;30647.540286895714;30850.382992340026;2718.361185121495;2721.102036328007;2751.3671855473153;2851.5152424087746;2892.7830412718918;533178;533661;540299;540311.8953215666;542644;64319.67840160769;64431;64751;64879;64975.70048599096;37821;38420;38787.03032424212;38868.22263554713;38897;16887.577810554834;17113.144342782813;17135.05757183342;17136.57158571046;17173.828261717474;12946.287954162612;13048.217106973612;13050.34748262602;13061.477540898448;13066;19948.401547953585;20037.59924801525;20138.986101389743;20148.395548133285;20254.392368228975;8073.434859559775;8162.102168761431;8185.140560241208;8186.666799961982;8258.710945116893;954.8997355277596;1041.3231399590175;1089.9945500272447;1095.6548687163518;1116.625930313338;29769.404611908074;30055.160761318068;30117.397652047275;30200.69799301979;30291.394172116434;986.8470387090013;1058.3385384134915;1125.0380924309761;1185.407296351824;1196.4436537010158;13119.27843968591;15574.143422111893;15674.373025079098;15864.889457738078;15994.440194593133;566932;589320;591377;592308;594816;58335.70832145812;60196;60536;60775.69612151911;60927.695361523794;1275.6938334799581;1414.3776738235144;1423.4662001749343;1495.6111411032991;1498.842621524724;29455.705442946153;29533.409331813673;29568;29872.701272986993;30319.545206821473;52587.47412525826;53036;53065.7346713264;53161.7341913288;53196.20205696995;462045;527041;527748;529685;532207;23490.295291141294;26830.195094147206;27470.763815627946;28144;29381.559276611297;35656.82171589125;37383.81308093442;45329.773351134354;45575.77212113918;45947.540524594326;38229;38769.55899726363;38798.836034919;39235.019124522114;39478.01304967399;35368.11579710528;36262.45606315855;38548.80725596354;38816.02959926025;40289;37966;38133.23733525258;38303.04242393962;38929.61070389373;39176.41235381414;27582.86208568944;33823;34321.97034088981;34369.656303437645;34764.65235347614;25285.62071568891;25310.114146004802;25336.366590835376;25770.484590307715;26070.60894086628 08:03:02 INFO - PROCESS | 1665 | |2;jquery.html;366.75610718871945;392.8094873986118;398.6491887139318;399.95000624921886;419.1679516160421;12233.266004039742;20025.699614505793;20745.273915412963;21315.36053918381;21380.25169119081;20156.59686806267;20512.487187820312;20522;21126.04932778025;21617.45956351092;5684.658920464785;6038.184845045917;6045.576809623328;6052.757889684407;6117.235345581802;6060.3939606039485;6093.329733729286;6297.496200303987;6915.861682766342;6916.135483064617;6773.830654233634;6819.590824550518;6848.315168483136;6868.210155832094;6873.656317184146;5916.171735956969;6873.450123990069;6874.759383421556;6893.931060689379;6894;5833;6036.426539478743;6050.2437195350585;6088.6042407243385;6098.115773212904;11463.36951467669;13812.309384530734;13837.836761514132;13920.303984800821;25287;1927.315802889968;2007.076744697441;2039.8470114741453;2147.914083436661;2176.8040876321047;9121.680741174094;10189.082982531534;10571.524281407279;14064.71870562583;15513.767293490606;1747.7116275814476;1761.5596100974756;1772.0785191700243;1774.7870255569285;1840.1443328852015;103453.44819827708;125121;126706;128143.71856281412;129531;11499.425028748528;11732.413379331;11753.471093800628;11771.117166212569;11888.702782430508;14761.114333140045;14858.851411485746;15052.849471505146;15124;15204.239788010555;8600.8709869352;8628.791969124328;8643.3949623527;8724.302055835518;8743.169398907094;8510.404271701045;8539.658413663385;8544.658213671508;8585.141485851365;8621.913780862236;3810.980945095312;4140.585941405835;4452.376667266585;4552.544745525421;4672.135654903854;1533.1031346662105;1662.534490342706;2009.5679428922851;2013.6878783788536;2019.5556977465228;816.8890309179511;872.6814712629845;993.7416271769248;1018.149844879519;1063.2450959818461;19594.70607940882;20422.183112675328;20706.275280365116;20978.0559874806;21048.89475552643;782.8473447677649;928.5774972387547;944.7118628818139;1004.4224570871778;1024.2113572549245;9272.443653380818;11031.062359699516;11037.448127593589;11067.391293478937;11127.387993660426;574331;616895;618135;632402;632611;116714;138471;142465;142938;144807.2759636195;1025.8922813104516;1032.5198782565992;1084.9132069434427;1104.9834252486219;1106.0653747583278;15743.370265189264;18084.638307234043;18635.254589816257;18680.439586812416;18710.438686839418;51888.962220756126;52998;54611.180832288344;54614;55023.449765503436;81129;82573.58713206396;83247.5837620808;83293;84194;7865.095514015961;7970.163132871197;8043.276105150565;8129.552874591717;8314.376421768271;10953.685557733123;11354.659360219206;11768.529258829893;11852.881471185177;12448.06639502059;8348.666053357798;10672.679819605424;10734.73163170927;10906.291091079054;11358.864113588575;10567.841482377617;10586;10633.787324253317;10635.14918806509;10838.187135964992;9136.31477639193;10769.676909692722;11068.225224234226;11219.382933938712;11299.717507062389;7907.288344049063;8036.799080023046;8055.718049868461;8170.7957301067945;8266.256036956704;10194.286399952229;10804.513796879002;11069.5018724156;11084.00243978046;11089.944550277198 08:03:02 INFO - PROCESS | 1665 | |3;mootools.html;1576.9290381932815;1652.2151977810543;1898.0699457265935;1960.451073699363;1970.2709997301001;8240.175982401772;8594.87107693385;8845.955770221148;9271.629134834608;9299.628014879405;6918.2389937107;8724.47653140812;8864.822703545926;8867.68963086292;8880.1563851434;2440.524097800925;2473.2332976777166;2476.319012271622;2477.578811602032;2483.801295896333;2421.624648179526;2453.9754602453927;2484.124486013058;2486.27897909606;2494.4886298308893;2452.9264122076365;2453.362125847275;2466.802655787542;2479.9008039678483;2482.441450673593;2400.9759902401015;2419.2984034629935;2432.914847980312;2434.7808697217247;2438.37821355555;2410.457647029404;2422.0917156066475;2426.0538390027905;2439.7682220189054;2452.8773561321864;8858.29133669305;8859.911400886038;8962.37263391563;8967.865482017774;8986.910130898737;4771.665983381165;5392;5551.333839939192;5551.528120109796;5561.749721262553;3158.652548219694;3234.078287688021;3507.736919731005;3649.94525082124;3701.7038636908983;939.6523286384013;954.746992047108;1058.3215357047352;1066.306900514664;1067.3329169269207;11101.44492775358;11412.600558980395;12006.279623222546;12102.939485302517;12245.938770306091;3947.822347994347;4738.218193998021;4770.809167633256;4801.231802911518;4811.975940120347;9509.762255943519;9523.28575356851;9533.570989305497;9535.094166054214;9544.570494327772;5374.704391258519;5408.1887716842;5458.61789674719;5458.781648734006;5488.094464413327;4562.954370456253;4798.73606951621;4973.602111831045;4987.725675087905;5099;3900.356441187229;3964.663003644665;4019.9397009044887;4037.9394309085387;4066.1054567994993;1251.8685538018558;1253.467276407523;1263.5577547858177;1265.987340126587;1269.9428525716364;777.587878424436;824.756696774453;918.9081091890891;947.9905200947902;960.9231261499064;5797.942020579824;5874.73563689635;5971.223740913653;6083.239595050619;6101.603395779349;461.91450092282867;463.74494028284306;464.1436549566113;471.4554689333825;473.67789902866383;3945.723799334019;4025.4364388985564;4026.57720939303;4093.5701751315682;4119.990602302455;11112.999830015176;11476.4835582399;11520.481578328996;11537.826932596017;11694.94152529249;11326.886731132749;11468.426578671033;11481.483333250024;11491.827622585668;11519.19365644397;7359.896015597703;7389.667464964197;8079.4344395892895;8567.18611731897;8607.698730544402;4788.880277993078;5052.72210028452;6145.354737752472;6195.783147589993;6391.169148010729;8770.868436973324;9901.50492475359;10147.949260253652;10406.947965260428;10624.628138015132;8970.730878073668;10365.896341036492;10517.579296828042;10714;10769.838452423062;6754.324567543206;7050.25972272904;7779.961100194463;7832.530048197126;7927.4054445915635;8616.827663446822;8648.740537783877;8709.259712924473;8709.782255443411;8713.25937295337;6543.967280163569;7436.256374362521;7583;7698.692052318069;7850.568218748023;7476.5887876167335;7588.696452142073;7592.16486186508;7654.73208437702;7698.307152356315;7222.313880181375;7290.307420795016;7295.744648937261;7325.4505912057875;7339.816504587215;4837.516248375135;4915.950840491692;5028.798848046184;5078.644494885322;5101.821436249714;4939.185034469272;4982.775775090202;4998.2002879539095;5022.447530771539;5206.245094461357 08:03:02 INFO - PROCESS | 1665 | |4;prototype.html;245.39387712350495;320.0462621388263;321.7827966122868;322.2154054876376;326.6227507229151;5429.212764149196;5476.91784623231;5514.255575497306;5531.929778972669;5551.361593416758;4679.368285281486;4808.567228949394;4926.630502712293;5156.613254005946;5425.918611220825;3927.7643341399607;3957.643812056914;4039.788063580929;4104.302268614343;4120.340745480724;3415.7456078987875;3822.4266360045935;3934.6065393460567;4136.793160341986;4147.460830092099;3682.5949145593963;3948.348522493785;3987.8604248851298;4111;4139.7930103494855;3811.3701545933495;3878.6897048236074;4074.960884974327;4117.341225403951;4124.298869192245;3272.8199949002797;3496.8984769797435;3534.9041797043;3537.9739875436094;3551.5738111426795;5170.276161337416;5205.349331333583;5209.635325527214;5213.7393130343335;5225.947740522584;4536.389581698482;4717.858464246078;4725.535084123898;4758.262469317261;4761.785719642625;5656.575756818255;5691.857703557403;5745.942540574582;5771.210924613383;5815.127730840408;3624.673779359844;3753.474513568103;3763.3414152523474;3912.5304963404574;3926.725129240954;5396.974574830809;5953.2856057272975;6043.395660433922;6076.726547305382;6169.722362493699;3932.7640341579595;3950.881473555798;3966.9801650991562;3967.6429121379215;4005.759654420744;4039.313316736103;4040.595940405936;4042.5148982121746;4048.9392659110135;4052.9797351013644;4004.65960393364;4006.5192176938954;4291.270484017662;4671.182543054952;4761.809607545407;4620.930686039712;4906.386701662293;5356.3572371315695;5572.9968605650565;5815.621984570989;1221.6579357779835;1378.9175497234824;1379.6688794689203;1382.198324971514;1382.377929931535;835.6128826148564;851.5018714052349;855.840336344257;863.1842908451579;900.4867225681298;4785.076237485298;5068.553383699046;5246.842594722164;5301.125314323171;5309.230161626542;4832.574390554795;5281.362777538975;5532.336119665585;5573.247611572467;5870.26621672291;4912.803487860517;4963.404391473046;5272.92090618641;5275.657082289639;5419.674819510842;2783.6381270434713;3096.969030309668;3154.8422578870964;3160.2731371784157;3172.127664892127;5821.554946922302;5925.762969481172;6011.579189456784;6150.602319341603;6188.195534580475;5797.217375654317;6063.423974722395;6065.666388348595;6099.725512351954;6117.357677443804;5640.915386269209;6063.363346848518;6084.269887613426;6132.78535251264;6212.751489940442;4881.536254055859;5295.735213239245;5656.60403771732;5863.970680146743;5953.255843019622;5821.79623713168;5864.413558644102;5883.352831188651;5888.558358123072;5888.673973780247;5022.166909078087;5237.921431178612;5675.070476038167;6045.576809623284;6149.0776383542825;3638.4900266389154;3677.2093999790172;3933.5476420212044;3965.821538030738;3979.7811120388656;5491.560675146058;5672.574556908331;5961.642301461753;5965.731542080704;6014.518838492822;4857.53853383928;5212.5569326607665;5537.9169312459535;5753.928585709225;5757.280339957505;4986.681193245158;5661.915071273852;5713.428657134419;5762.394948530344;5787.55311172207;5154.716490593053;5383.9192412114635;5591.944080559142;5594.776208951596;5711.485966263056;5354.651947623392;5590.170733047875;5788.652680839163;5800;5813.081683044501;5223.4776522347465;5508.504234618904;5553.722973580812;5566.721663916707;5773.826785196451 08:03:02 INFO - PROCESS | 1665 | |5;yui.html;676.5331920974527;734.5702763883128;751.9172890981993;766.1534004924558;782.5656760497922;12017;15026.549203523911;15138.243087845618;15202.315895784688;15371.615709607251;13404.809679254895;14184;14641.560753177395;14688;14723.779143312831;695.408902432933;889.9021107678183;894.369469523984;900.4642237868491;913.2100732866089;902.0979020979021;908.9227415669643;909.8999110097884;914.5335878701843;914.7758799094231;970.5389939778619;970.815545046439;971.2181693736575;971.6113554578154;972.8540718892187;954.6897258390982;956.8564715292692;956.9090936361034;957.1720461800562;958.4584709639066;721.989170162448;722.887952367384;723.4284914917209;726.458788202787;726.5350175887436;15146.621334466616;15269.694606107816;15283.312250948735;15298.923505382512;15307.311170997333;12554.497820087277;12793;12888.742225155538;12898;13313.733725325439;10169.084782369548;10195.337303075275;10246;10289.813372491051;10553.630622928238;3493.8602455901987;3497.300539892032;3511.10466830959;3536.504889315498;3629;65984;85286.57356713177;85602.57198714028;89161.55419222928;89563;8298.377621678399;9618.759531011641;9642.566084526214;9647.421154730739;9980.500974951223;22853;22862.31413057611;22867.97094130768;22870.885645571667;22914.312570622908;7467.178610352857;7523.924760752322;7553.055868016498;7661.693532258759;7867.567283799446;3849.191669749384;3913.6477717005605;4062.837486500566;4064.9593504064583;4081.0613558881623;3096.551000104972;3489.7208223342072;3553.4136867416587;3588.4258518636893;3593.2454184621524;540.3785646506549;609.3395497269904;623.329920335641;632.0424556796457;634.9746010159635;486.0764547360044;564.5398999815137;579.6203486716207;606.0575804623852;613.6962203709103;7368.410527157922;8164.142765009708;8231.588420578948;8367.079621241659;8409.285210757032;542.7557599080429;565.451512033327;596.6658671144087;604.0052479144473;607.3622696169004;3773.5471743390967;3903.781164953677;3931.4102884566973;3965.266425711253;4088.6729061674996;88431.55784221167;89656.55171724099;90519;91143;99980;77319.22680773232;77510.6124469374;78787;86934;87642;70495.64752176206;71476.64261678659;73607.26392736111;76291;76516;8296.70961516344;8302.335813135056;8369.874451883104;8454.661813527637;8566.914330856862;40758.592414076666;42219.36670949877;43590.78204608957;48188.75905620449;48474;62963;65575.67212163909;65625.68748624905;65897;66124;10850.403227822546;11006.416498139837;11210.103191744804;11333.093352531612;11344.773104538028;9793.706188814347;11940.2835829847;11991.100667450151;12040.819387709016;12145.21056131384;11403.828942565702;11417.257878237888;11453.312801231952;11458.885411146115;11674;11501.19491635611;11559.711007224887;11807.468663910317;11877.52489900429;11924.926756591949;11272.774544509228;11362.204645674723;11413.086953043903;11428.771424571629;11661.24201926872;8767.342449316198;9404.905950940676;9408.81182376335;9665.27510436706;9850.802983940137;7514.812129696801;8643;8681.913180868363;9164.083591640783;9291.117343852324 08:03:02 INFO - PROCESS | 1665 | __end_tp_report 08:03:02 INFO - PROCESS | 1665 | __start_cc_report 08:03:02 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,422 08:03:02 INFO - PROCESS | 1665 | __end_cc_report 08:03:02 INFO - PROCESS | 1665 | __startTimestamp1463497382764__endTimestamp 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | ------- Summary: start ------- 08:03:02 INFO - PROCESS | 1665 | Number of tests: 6 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5846.80 Median:6146.32 stddev:755.79 (12.3%) stddev-sans-first:735.28 08:03:02 INFO - PROCESS | 1665 | Values: 3496.8 3531.8 3548.3 3558.2 3574.6 5944.2 5961.7 6005.8 6044.8 6053.4 5863.8 5953.3 5971.1 5979.3 5990.4 5267.9 5303.7 5402.2 5413.5 5503.3 385.1 5303.8 5411.1 5418.1 5492.5 5360.1 5363.8 5379.4 5412.0 5428.0 5217.7 5419.2 5435.9 5443.7 5460.1 5245.0 5254.7 5281.7 5317.3 5325.9 5798.1 5909.3 5981.2 5992.5 6006.4 6177.9 6184.1 6253.7 6296.6 6297.7 6245.7 6364.2 6366.9 6391.4 6392.8 6129.8 6148.9 6164.9 6187.0 6208.5 6256.7 6305.2 6312.3 6476.2 6481.0 6216.0 6221.2 6222.1 6242.0 6244.3 5374.7 5816.5 6282.5 6293.2 6337.9 4702.6 6171.8 6179.5 6201.8 6214.9 6157.5 6168.2 6208.0 6209.2 6227.6 5085.2 5140.4 5146.4 5148.9 5158.1 4534.1 4538.1 4574.2 4590.2 4667.1 6034.6 6105.3 6111.8 6125.1 6146.3 6323.0 6341.5 6348.0 6349.1 6355.0 5963.5 6046.4 6085.5 6122.8 6129.1 5625.2 5637.5 5639.0 5644.8 5650.2 6298.4 6530.9 6553.4 6562.1 6574.9 6220.6 6233.8 6282.3 6358.3 6360.4 5159.3 6520.4 6533.3 6539.3 6606.3 6182.9 6197.3 6270.9 6272.7 6284.6 6190.8 6323.7 6333.3 6340.5 6362.8 5474.2 5960.3 6190.2 6253.7 6256.8 6136.6 6173.1 6243.7 6268.2 6280.8 6035.5 6074.6 6160.9 6199.7 6224.7 6195.2 6250.5 6260.9 6262.8 6268.5 5503.4 6129.1 6140.0 6284.5 6291.9 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:67946.96 Median:38913.31 stddev:146172.34 (375.6%) stddev-sans-first:146494.65 08:03:02 INFO - PROCESS | 1665 | Values: 492.8 532.3 550.6 555.2 571.5 28749.9 42762.6 42790.1 42830.8 42916.8 43425.1 43818.9 43830.8 43964.9 44012.9 8434.5 8466.2 9513.5 9713.0 9766.2 9641.7 9664.1 9755.7 9764.3 9858.0 9753.3 9758.7 9786.1 9788.1 9802.4 9727.2 9783.0 9789.2 9798.7 9886.8 9646.0 9668.7 9694.1 9695.1 9699.9 39696.6 40047.6 40087.4 40088.0 40095.8 3376.8 3431.6 3504.5 3586.5 3860.5 29578.9 29634.3 30301.0 30647.5 30850.4 2718.4 2721.1 2751.4 2851.5 2892.8 533178.0 533661.0 540299.0 540311.9 542644.0 64319.7 64431.0 64751.0 64879.0 64975.7 37821.0 38420.0 38787.0 38868.2 38897.0 16887.6 17113.1 17135.1 17136.6 17173.8 12946.3 13048.2 13050.3 13061.5 13066.0 19948.4 20037.6 20139.0 20148.4 20254.4 8073.4 8162.1 8185.1 8186.7 8258.7 954.9 1041.3 1090.0 1095.7 1116.6 29769.4 30055.2 30117.4 30200.7 30291.4 986.8 1058.3 1125.0 1185.4 1196.4 13119.3 15574.1 15674.4 15864.9 15994.4 566932.0 589320.0 591377.0 592308.0 594816.0 58335.7 60196.0 60536.0 60775.7 60927.7 1275.7 1414.4 1423.5 1495.6 1498.8 29455.7 29533.4 29568.0 29872.7 30319.5 52587.5 53036.0 53065.7 53161.7 53196.2 462045.0 527041.0 527748.0 529685.0 532207.0 23490.3 26830.2 27470.8 28144.0 29381.6 35656.8 37383.8 45329.8 45575.8 45947.5 38229.0 38769.6 38798.8 39235.0 39478.0 35368.1 36262.5 38548.8 38816.0 40289.0 37966.0 38133.2 38303.0 38929.6 39176.4 27582.9 33823.0 34322.0 34369.7 34764.7 25285.6 25310.1 25336.4 25770.5 26070.6 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:35770.02 Median:20861.66 stddev:103002.18 (493.7%) stddev-sans-first:103256.82 08:03:02 INFO - PROCESS | 1665 | Values: 366.8 392.8 398.6 400.0 419.2 12233.3 20025.7 20745.3 21315.4 21380.3 20156.6 20512.5 20522.0 21126.0 21617.5 5684.7 6038.2 6045.6 6052.8 6117.2 6060.4 6093.3 6297.5 6915.9 6916.1 6773.8 6819.6 6848.3 6868.2 6873.7 5916.2 6873.5 6874.8 6893.9 6894.0 5833.0 6036.4 6050.2 6088.6 6098.1 11463.4 13812.3 13837.8 13920.3 25287.0 1927.3 2007.1 2039.8 2147.9 2176.8 9121.7 10189.1 10571.5 14064.7 15513.8 1747.7 1761.6 1772.1 1774.8 1840.1 103453.4 125121.0 126706.0 128143.7 129531.0 11499.4 11732.4 11753.5 11771.1 11888.7 14761.1 14858.9 15052.8 15124.0 15204.2 8600.9 8628.8 8643.4 8724.3 8743.2 8510.4 8539.7 8544.7 8585.1 8621.9 3811.0 4140.6 4452.4 4552.5 4672.1 1533.1 1662.5 2009.6 2013.7 2019.6 816.9 872.7 993.7 1018.1 1063.2 19594.7 20422.2 20706.3 20978.1 21048.9 782.8 928.6 944.7 1004.4 1024.2 9272.4 11031.1 11037.4 11067.4 11127.4 574331.0 616895.0 618135.0 632402.0 632611.0 116714.0 138471.0 142465.0 142938.0 144807.3 1025.9 1032.5 1084.9 1105.0 1106.1 15743.4 18084.6 18635.3 18680.4 18710.4 51889.0 52998.0 54611.2 54614.0 55023.4 81129.0 82573.6 83247.6 83293.0 84194.0 7865.1 7970.2 8043.3 8129.6 8314.4 10953.7 11354.7 11768.5 11852.9 12448.1 8348.7 10672.7 10734.7 10906.3 11358.9 10567.8 10586.0 10633.8 10635.1 10838.2 9136.3 10769.7 11068.2 11219.4 11299.7 7907.3 8036.8 8055.7 8170.8 8266.3 10194.3 10804.5 11069.5 11084.0 11089.9 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5787.87 Median:4927.57 stddev:3293.54 (66.8%) stddev-sans-first:3287.58 08:03:02 INFO - PROCESS | 1665 | Values: 1576.9 1652.2 1898.1 1960.5 1970.3 8240.2 8594.9 8846.0 9271.6 9299.6 6918.2 8724.5 8864.8 8867.7 8880.2 2440.5 2473.2 2476.3 2477.6 2483.8 2421.6 2454.0 2484.1 2486.3 2494.5 2452.9 2453.4 2466.8 2479.9 2482.4 2401.0 2419.3 2432.9 2434.8 2438.4 2410.5 2422.1 2426.1 2439.8 2452.9 8858.3 8859.9 8962.4 8967.9 8986.9 4771.7 5392.0 5551.3 5551.5 5561.7 3158.7 3234.1 3507.7 3649.9 3701.7 939.7 954.7 1058.3 1066.3 1067.3 11101.4 11412.6 12006.3 12102.9 12245.9 3947.8 4738.2 4770.8 4801.2 4812.0 9509.8 9523.3 9533.6 9535.1 9544.6 5374.7 5408.2 5458.6 5458.8 5488.1 4563.0 4798.7 4973.6 4987.7 5099.0 3900.4 3964.7 4019.9 4037.9 4066.1 1251.9 1253.5 1263.6 1266.0 1269.9 777.6 824.8 918.9 948.0 960.9 5797.9 5874.7 5971.2 6083.2 6101.6 461.9 463.7 464.1 471.5 473.7 3945.7 4025.4 4026.6 4093.6 4120.0 11113.0 11476.5 11520.5 11537.8 11694.9 11326.9 11468.4 11481.5 11491.8 11519.2 7359.9 7389.7 8079.4 8567.2 8607.7 4788.9 5052.7 6145.4 6195.8 6391.2 8770.9 9901.5 10147.9 10406.9 10624.6 8970.7 10365.9 10517.6 10714.0 10769.8 6754.3 7050.3 7780.0 7832.5 7927.4 8616.8 8648.7 8709.3 8709.8 8713.3 6544.0 7436.3 7583.0 7698.7 7850.6 7476.6 7588.7 7592.2 7654.7 7698.3 7222.3 7290.3 7295.7 7325.5 7339.8 4837.5 4916.0 5028.8 5078.6 5101.8 4939.2 4982.8 4998.2 5022.4 5206.2 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4628.36 Median:5218.61 stddev:1431.79 (27.4%) stddev-sans-first:1397.50 08:03:02 INFO - PROCESS | 1665 | Values: 245.4 320.0 321.8 322.2 326.6 5429.2 5476.9 5514.3 5531.9 5551.4 4679.4 4808.6 4926.6 5156.6 5425.9 3927.8 3957.6 4039.8 4104.3 4120.3 3415.7 3822.4 3934.6 4136.8 4147.5 3682.6 3948.3 3987.9 4111.0 4139.8 3811.4 3878.7 4075.0 4117.3 4124.3 3272.8 3496.9 3534.9 3538.0 3551.6 5170.3 5205.3 5209.6 5213.7 5225.9 4536.4 4717.9 4725.5 4758.3 4761.8 5656.6 5691.9 5745.9 5771.2 5815.1 3624.7 3753.5 3763.3 3912.5 3926.7 5397.0 5953.3 6043.4 6076.7 6169.7 3932.8 3950.9 3967.0 3967.6 4005.8 4039.3 4040.6 4042.5 4048.9 4053.0 4004.7 4006.5 4291.3 4671.2 4761.8 4620.9 4906.4 5356.4 5573.0 5815.6 1221.7 1378.9 1379.7 1382.2 1382.4 835.6 851.5 855.8 863.2 900.5 4785.1 5068.6 5246.8 5301.1 5309.2 4832.6 5281.4 5532.3 5573.2 5870.3 4912.8 4963.4 5272.9 5275.7 5419.7 2783.6 3097.0 3154.8 3160.3 3172.1 5821.6 5925.8 6011.6 6150.6 6188.2 5797.2 6063.4 6065.7 6099.7 6117.4 5640.9 6063.4 6084.3 6132.8 6212.8 4881.5 5295.7 5656.6 5864.0 5953.3 5821.8 5864.4 5883.4 5888.6 5888.7 5022.2 5237.9 5675.1 6045.6 6149.1 3638.5 3677.2 3933.5 3965.8 3979.8 5491.6 5672.6 5961.6 5965.7 6014.5 4857.5 5212.6 5537.9 5753.9 5757.3 4986.7 5661.9 5713.4 5762.4 5787.6 5154.7 5383.9 5591.9 5594.8 5711.5 5354.7 5590.2 5788.7 5800.0 5813.1 5223.5 5508.5 5553.7 5566.7 5773.8 08:03:02 INFO - PROCESS | 1665 | 08:03:02 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:18370.01 Median:618.51 stddev:26056.03 (4212.7%) stddev-sans-first:26095.26 08:03:02 INFO - PROCESS | 1665 | Values: 676.5 734.6 751.9 766.2 782.6 12017.0 15026.5 15138.2 15202.3 15371.6 13404.8 14184.0 14641.6 14688.0 14723.8 695.4 889.9 894.4 900.5 913.2 902.1 908.9 909.9 914.5 914.8 970.5 970.8 971.2 971.6 972.9 954.7 956.9 956.9 957.2 958.5 722.0 722.9 723.4 726.5 726.5 15146.6 15269.7 15283.3 15298.9 15307.3 12554.5 12793.0 12888.7 12898.0 13313.7 10169.1 10195.3 10246.0 10289.8 10553.6 3493.9 3497.3 3511.1 3536.5 3629.0 65984.0 85286.6 85602.6 89161.6 89563.0 8298.4 9618.8 9642.6 9647.4 9980.5 22853.0 22862.3 22868.0 22870.9 22914.3 7467.2 7523.9 7553.1 7661.7 7867.6 3849.2 3913.6 4062.8 4065.0 4081.1 3096.6 3489.7 3553.4 3588.4 3593.2 540.4 609.3 623.3 632.0 635.0 486.1 564.5 579.6 606.1 613.7 7368.4 8164.1 8231.6 8367.1 8409.3 542.8 565.5 596.7 604.0 607.4 3773.5 3903.8 3931.4 3965.3 4088.7 88431.6 89656.6 90519.0 91143.0 99980.0 77319.2 77510.6 78787.0 86934.0 87642.0 70495.6 71476.6 73607.3 76291.0 76516.0 8296.7 8302.3 8369.9 8454.7 8566.9 40758.6 42219.4 43590.8 48188.8 48474.0 62963.0 65575.7 65625.7 65897.0 66124.0 10850.4 11006.4 11210.1 11333.1 11344.8 9793.7 11940.3 11991.1 12040.8 12145.2 11403.8 11417.3 11453.3 11458.9 11674.0 11501.2 11559.7 11807.5 11877.5 11924.9 11272.8 11362.2 11413.1 11428.8 11661.2 8767.3 9404.9 9408.8 9665.3 9850.8 7514.8 8643.0 8681.9 9164.1 9291.1 08:03:02 INFO - PROCESS | 1665 | -------- Summary: end -------- 08:03:02 INFO - PROCESS | 1665 | 08:03:03 INFO - TEST-INFO | 1665: exit 0 08:03:03 INFO - TEST-OK | dromaeo_css | took 1112597ms 08:03:03 INFO - TEST-START | kraken 08:03:03 INFO - operating with platform_type : mac_ 08:03:03 INFO - Initialising browser for kraken test... 08:03:03 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp8F1AO5/profile http://localhost:49277/getInfo.html) 08:03:06 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 08:03:06 INFO - PROCESS | 1676 | colorDepth:24 08:03:06 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 08:03:06 INFO - PROCESS | 1676 | __metrics 08:03:06 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 08:03:06 INFO - TEST-INFO | 1676: exit 0 08:03:06 INFO - Browser initialized. 08:03:06 INFO - Running cycle 1/1 for kraken test... 08:03:06 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp8F1AO5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:18 INFO - PROCESS | 1679 | RSS: Main: 249810944 08:03:18 INFO - PROCESS | 1679 | 08:03:21 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:03:22 INFO - PROCESS | 1679 | RSS: Main: 261685248 08:03:22 INFO - PROCESS | 1679 | 08:03:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:03:26 INFO - PROCESS | 1679 | RSS: Main: 266399744 08:03:26 INFO - PROCESS | 1679 | 08:03:29 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:03:30 INFO - PROCESS | 1679 | RSS: Main: 266690560 08:03:30 INFO - PROCESS | 1679 | 08:03:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:03:32 INFO - PROCESS | 1679 | RSS: Main: 267399168 08:03:32 INFO - PROCESS | 1679 | 08:03:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:03:35 INFO - PROCESS | 1679 | RSS: Main: 268509184 08:03:35 INFO - PROCESS | 1679 | 08:03:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:03:39 INFO - PROCESS | 1679 | RSS: Main: 268394496 08:03:39 INFO - PROCESS | 1679 | 08:03:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:03:43 INFO - PROCESS | 1679 | RSS: Main: 269148160 08:03:43 INFO - PROCESS | 1679 | 08:03:46 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:03:46 INFO - PROCESS | 1679 | RSS: Main: 269033472 08:03:46 INFO - PROCESS | 1679 | 08:03:48 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:03:49 INFO - PROCESS | 1679 | RSS: Main: 268144640 08:03:49 INFO - PROCESS | 1679 | 08:03:51 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:03:51 INFO - PROCESS | 1679 | RSS: Main: 268251136 08:03:51 INFO - PROCESS | 1679 | 08:03:54 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:03:54 INFO - PROCESS | 1679 | RSS: Main: 268574720 08:03:54 INFO - PROCESS | 1679 | 08:03:57 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:03:58 INFO - PROCESS | 1679 | RSS: Main: 268701696 08:03:58 INFO - PROCESS | 1679 | 08:04:01 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:04:02 INFO - PROCESS | 1679 | RSS: Main: 268673024 08:04:02 INFO - PROCESS | 1679 | 08:04:03 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:04:04 INFO - PROCESS | 1679 | RSS: Main: 270475264 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | __start_tp_report 08:04:04 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 08:04:04 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 08:04:04 INFO - PROCESS | 1679 | |i|pagename|runs| 08:04:04 INFO - PROCESS | 1679 | |0;ai-astar;110;114;106;114;115;114;105;113;125;115 08:04:04 INFO - PROCESS | 1679 | |1;audio-beat-detection;192;120;185;209;126;168;129;143;181;146 08:04:04 INFO - PROCESS | 1679 | |2;audio-dft;195;200;217;212;195;194;190;270;271;198 08:04:04 INFO - PROCESS | 1679 | |3;audio-fft;74;82;76;177;58;67;80;91;140;59 08:04:04 INFO - PROCESS | 1679 | |4;audio-oscillator;81;86;144;77;80;87;122;84;83;122 08:04:04 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;96;129;88;86;88;85;81;129;118;85 08:04:04 INFO - PROCESS | 1679 | |6;imaging-darkroom;100;96;106;149;101;95;96;100;94;94 08:04:04 INFO - PROCESS | 1679 | |7;imaging-desaturate;129;90;126;141;91;96;90;92;93;91 08:04:04 INFO - PROCESS | 1679 | |8;json-parse-financial;58;61;57;60;60;59;60;117;62;59 08:04:04 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;65;66;67;71;65;75;69;67;65;68 08:04:04 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;86;81;101;106;112;105;107;84;87;86 08:04:04 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;138;138;136;131;136;134;154;148;220;143 08:04:04 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;184;219;215;168;210;218;180;179;221;158 08:04:04 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;66;65;66;66;66;63;67;64;64;65 08:04:04 INFO - PROCESS | 1679 | __end_tp_report 08:04:04 INFO - PROCESS | 1679 | __start_cc_report 08:04:04 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1078 08:04:04 INFO - PROCESS | 1679 | __end_cc_report 08:04:04 INFO - PROCESS | 1679 | __startTimestamp1463497444180__endTimestamp 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | ------- Summary: start ------- 08:04:04 INFO - PROCESS | 1679 | Number of tests: 14 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:5.55 (4.9%) stddev-sans-first:5.77 08:04:04 INFO - PROCESS | 1679 | Values: 110.0 114.0 106.0 114.0 115.0 114.0 105.0 113.0 125.0 115.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:159.90 Median:174.50 stddev:31.19 (17.9%) stddev-sans-first:30.85 08:04:04 INFO - PROCESS | 1679 | Values: 192.0 120.0 185.0 209.0 126.0 168.0 129.0 143.0 181.0 146.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:214.20 Median:206.00 stddev:30.82 (15.0%) stddev-sans-first:31.89 08:04:04 INFO - PROCESS | 1679 | Values: 195.0 200.0 217.0 212.0 195.0 194.0 190.0 270.0 271.0 198.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:90.40 Median:75.00 stddev:38.29 (51.1%) stddev-sans-first:40.16 08:04:04 INFO - PROCESS | 1679 | Values: 74.0 82.0 76.0 177.0 58.0 67.0 80.0 91.0 140.0 59.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:82.00 stddev:23.54 (28.7%) stddev-sans-first:24.28 08:04:04 INFO - PROCESS | 1679 | Values: 81.0 86.0 144.0 77.0 80.0 87.0 122.0 84.0 83.0 122.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:85.50 stddev:19.13 (22.4%) stddev-sans-first:20.27 08:04:04 INFO - PROCESS | 1679 | Values: 96.0 129.0 88.0 86.0 88.0 85.0 81.0 129.0 118.0 85.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:94.00 stddev:16.57 (17.6%) stddev-sans-first:17.54 08:04:04 INFO - PROCESS | 1679 | Values: 100.0 96.0 106.0 149.0 101.0 95.0 96.0 100.0 94.0 94.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:103.90 Median:91.00 stddev:19.82 (21.8%) stddev-sans-first:18.83 08:04:04 INFO - PROCESS | 1679 | Values: 129.0 90.0 126.0 141.0 91.0 96.0 90.0 92.0 93.0 91.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:60.00 stddev:18.22 (30.4%) stddev-sans-first:19.13 08:04:04 INFO - PROCESS | 1679 | Values: 58.0 61.0 57.0 60.0 60.0 59.0 60.0 117.0 62.0 59.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:67.50 stddev:3.19 (4.7%) stddev-sans-first:3.22 08:04:04 INFO - PROCESS | 1679 | Values: 65.0 66.0 67.0 71.0 65.0 75.0 69.0 67.0 65.0 68.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:95.50 Median:82.50 stddev:11.69 (14.2%) stddev-sans-first:11.89 08:04:04 INFO - PROCESS | 1679 | Values: 86.0 81.0 101.0 106.0 112.0 105.0 107.0 84.0 87.0 86.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:140.50 stddev:26.28 (18.7%) stddev-sans-first:27.64 08:04:04 INFO - PROCESS | 1679 | Values: 138.0 138.0 136.0 131.0 136.0 134.0 154.0 148.0 220.0 143.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.20 Median:212.50 stddev:23.82 (11.2%) stddev-sans-first:24.92 08:04:04 INFO - PROCESS | 1679 | Values: 184.0 219.0 215.0 168.0 210.0 218.0 180.0 179.0 221.0 158.0 08:04:04 INFO - PROCESS | 1679 | 08:04:04 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.27 08:04:04 INFO - PROCESS | 1679 | Values: 66.0 65.0 66.0 66.0 66.0 63.0 67.0 64.0 64.0 65.0 08:04:04 INFO - PROCESS | 1679 | -------- Summary: end -------- 08:04:04 INFO - PROCESS | 1679 | 08:04:05 INFO - TEST-INFO | 1679: exit 0 08:04:05 INFO - TEST-OK | kraken | took 61736ms 08:04:05 INFO - SUITE-END | took 1174s 08:04:05 INFO - Completed test suite (00:19:34) 08:04:05 INFO - javascript benchmark 08:04:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7248.9313223764975, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3496.842642081106, 3531.805750683712, 3548.2903419316144, 3558.199405133845, 3574.6067932527417, 5944.197533332998, 5961.672108034062, 6005.819825405234, 6044.758209671608, 6053.364396738345, 5863.76544938202, 5953.285605727308, 5971.134185543094, 5979.282486101674, 5990.4309090636425, 5267.86830329242, 5303.681779093247, 5402.162664786964, 5413.458654134594, 5503.257060296849, 385.0555884117415, 5303.813576172102, 5411.107167317407, 5418.051840927842, 5492.450754924515, 5360.088784906537, 5363.758630861621, 5379.35447746271, 5411.9717253721865, 5427.972860135714, 5217.686938783685, 5419.241306217133, 5435.945640543583, 5443.673379597237, 5460.099083651193, 5245.0, 5254.710990895499, 5281.683099014072, 5317.308749862493, 5325.946740532622, 5798.130280457923, 5909.290885093773, 5981.192539007222, 5992.520598352121, 6006.399360064002, 6177.907331390033, 6184.134221209034, 6253.7185826637915, 6296.590721603127, 6297.6536290504, 6245.687715614201, 6364.172657554534, 6366.93633063668, 6391.392817682313, 6392.776252831129, 6129.846753831145, 6148.8770224595255, 6164.87670246597, 6187.041008643668, 6208.4722798562625, 6256.74973001084, 6305.211848518935, 6312.305646378986, 6476.222853257639, 6480.967595161994, 6215.96892015537, 6221.160143380676, 6222.097795819581, 6241.968790156111, 6244.344343843922, 5374.70439125844, 5816.534677225812, 6282.465990390868, 6293.1504246927, 6337.904931426032, 4702.553257440538, 6171.84570385735, 6179.5056395488255, 6201.81394558164, 6214.875702486015, 6157.507399408037, 6168.19813424252, 6208.0, 6209.161763161916, 6227.626342419469, 5085.186370180753, 5140.3831540215415, 5146.382434107931, 5148.9485105149215, 5158.09733296679, 4534.138513682456, 4538.069695712371, 4574.153781550423, 4590.173768721662, 4667.13657973276, 6034.607750496291, 6105.32841387447, 6111.755529778848, 6125.142480052797, 6146.323904370515, 6323.0, 6341.460975817107, 6348.0, 6349.079383489368, 6355.046742988588, 5963.493103086286, 6046.3651316612, 6085.513158947274, 6122.7857025004805, 6129.080637904261, 5625.156226566048, 5637.49262566371, 5638.971805140948, 5644.802431914945, 5650.208970744099, 6298.433141017331, 6530.869382612226, 6553.4101930826455, 6562.114114594564, 6574.868502630016, 6220.564560480723, 6233.750649974132, 6282.277538083179, 6358.2687990879795, 6360.39576240275, 5159.251908473176, 6520.413162815369, 6533.314002029719, 6539.313372095861, 6606.306337834651, 6182.876342473035, 6197.287311959182, 6270.905936410866, 6272.717727702356, 6284.591501552567, 6190.783322583688, 6323.747050117944, 6333.303336633059, 6340.4927605792345, 6362.77730279438, 5474.206240095244, 5960.314563825043, 6190.181847184509, 6253.718582663882, 6256.843578910563, 6136.58034916419, 6173.135760993375, 6243.656598886923, 6268.247810262797, 6280.842978925563, 6035.482345915514, 6074.635521868702, 6160.936086411102, 6199.721012554536, 6224.688765561795, 6195.19462469876, 6250.531210159165, 6260.874782504416, 6262.812115636538, 6268.4671802897265, 5503.422140675331, 6129.053167990421, 6140.0134868502855, 6284.528660350401, 6291.905621415591], "value": 5804.734019123209, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.75116066386494, 532.2521856791205, 550.6420826462798, 555.2088274209253, 571.5341996273037, 28749.85625071874, 42762.572374276286, 42790.144197116075, 42830.7858460708, 42916.785416072875, 43425.131497370036, 43818.90452738683, 43830.780846095724, 43964.90087747808, 44012.89967750808, 8434.53610051447, 8466.195711407407, 9513.5243237838, 9713.028697130267, 9766.218702503782, 9641.662541811038, 9664.130228279453, 9755.707328780149, 9764.267679923998, 9857.950710246474, 9753.268504862128, 9758.707238782848, 9786.070323319273, 9788.119069283763, 9802.36284641496, 9727.221822254238, 9782.951085244527, 9789.21686265097, 9798.657047003391, 9886.752831179278, 9646.0, 9668.661596844147, 9694.127528522467, 9695.078967498077, 9699.90300096997, 39696.61061862821, 40047.59952400497, 40087.39868901969, 40088.0, 40095.7995210025, 3376.8142752148624, 3431.6396778338412, 3504.4743288506675, 3586.4799604057266, 3860.4595356650093, 29578.852105739334, 29634.25914352137, 30301.0, 30647.540286895714, 30850.382992340026, 2718.361185121495, 2721.102036328007, 2751.3671855473153, 2851.5152424087746, 2892.7830412718918, 533178.0, 533661.0, 540299.0, 540311.8953215666, 542644.0, 64319.67840160769, 64431.0, 64751.0, 64879.0, 64975.70048599096, 37821.0, 38420.0, 38787.03032424212, 38868.22263554713, 38897.0, 16887.577810554834, 17113.144342782813, 17135.05757183342, 17136.57158571046, 17173.828261717474, 12946.287954162612, 13048.217106973612, 13050.34748262602, 13061.477540898448, 13066.0, 19948.401547953585, 20037.59924801525, 20138.986101389743, 20148.395548133285, 20254.392368228975, 8073.434859559775, 8162.102168761431, 8185.140560241208, 8186.666799961982, 8258.710945116893, 954.8997355277596, 1041.3231399590175, 1089.9945500272447, 1095.6548687163518, 1116.625930313338, 29769.404611908074, 30055.160761318068, 30117.397652047275, 30200.69799301979, 30291.394172116434, 986.8470387090013, 1058.3385384134915, 1125.0380924309761, 1185.407296351824, 1196.4436537010158, 13119.27843968591, 15574.143422111893, 15674.373025079098, 15864.889457738078, 15994.440194593133, 566932.0, 589320.0, 591377.0, 592308.0, 594816.0, 58335.70832145812, 60196.0, 60536.0, 60775.69612151911, 60927.695361523794, 1275.6938334799581, 1414.3776738235144, 1423.4662001749343, 1495.6111411032991, 1498.842621524724, 29455.705442946153, 29533.409331813673, 29568.0, 29872.701272986993, 30319.545206821473, 52587.47412525826, 53036.0, 53065.7346713264, 53161.7341913288, 53196.20205696995, 462045.0, 527041.0, 527748.0, 529685.0, 532207.0, 23490.295291141294, 26830.195094147206, 27470.763815627946, 28144.0, 29381.559276611297, 35656.82171589125, 37383.81308093442, 45329.773351134354, 45575.77212113918, 45947.540524594326, 38229.0, 38769.55899726363, 38798.836034919, 39235.019124522114, 39478.01304967399, 35368.11579710528, 36262.45606315855, 38548.80725596354, 38816.02959926025, 40289.0, 37966.0, 38133.23733525258, 38303.04242393962, 38929.61070389373, 39176.41235381414, 27582.86208568944, 33823.0, 34321.97034088981, 34369.656303437645, 34764.65235347614, 25285.62071568891, 25310.114146004802, 25336.366590835376, 25770.484590307715, 26070.60894086628], "value": 19880.7487673011, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.75610718871945, 392.8094873986118, 398.6491887139318, 399.95000624921886, 419.1679516160421, 12233.266004039742, 20025.699614505793, 20745.273915412963, 21315.36053918381, 21380.25169119081, 20156.59686806267, 20512.487187820312, 20522.0, 21126.04932778025, 21617.45956351092, 5684.658920464785, 6038.184845045917, 6045.576809623328, 6052.757889684407, 6117.235345581802, 6060.3939606039485, 6093.329733729286, 6297.496200303987, 6915.861682766342, 6916.135483064617, 6773.830654233634, 6819.590824550518, 6848.315168483136, 6868.210155832094, 6873.656317184146, 5916.171735956969, 6873.450123990069, 6874.759383421556, 6893.931060689379, 6894.0, 5833.0, 6036.426539478743, 6050.2437195350585, 6088.6042407243385, 6098.115773212904, 11463.36951467669, 13812.309384530734, 13837.836761514132, 13920.303984800821, 25287.0, 1927.315802889968, 2007.076744697441, 2039.8470114741453, 2147.914083436661, 2176.8040876321047, 9121.680741174094, 10189.082982531534, 10571.524281407279, 14064.71870562583, 15513.767293490606, 1747.7116275814476, 1761.5596100974756, 1772.0785191700243, 1774.7870255569285, 1840.1443328852015, 103453.44819827708, 125121.0, 126706.0, 128143.71856281412, 129531.0, 11499.425028748528, 11732.413379331, 11753.471093800628, 11771.117166212569, 11888.702782430508, 14761.114333140045, 14858.851411485746, 15052.849471505146, 15124.0, 15204.239788010555, 8600.8709869352, 8628.791969124328, 8643.3949623527, 8724.302055835518, 8743.169398907094, 8510.404271701045, 8539.658413663385, 8544.658213671508, 8585.141485851365, 8621.913780862236, 3810.980945095312, 4140.585941405835, 4452.376667266585, 4552.544745525421, 4672.135654903854, 1533.1031346662105, 1662.534490342706, 2009.5679428922851, 2013.6878783788536, 2019.5556977465228, 816.8890309179511, 872.6814712629845, 993.7416271769248, 1018.149844879519, 1063.2450959818461, 19594.70607940882, 20422.183112675328, 20706.275280365116, 20978.0559874806, 21048.89475552643, 782.8473447677649, 928.5774972387547, 944.7118628818139, 1004.4224570871778, 1024.2113572549245, 9272.443653380818, 11031.062359699516, 11037.448127593589, 11067.391293478937, 11127.387993660426, 574331.0, 616895.0, 618135.0, 632402.0, 632611.0, 116714.0, 138471.0, 142465.0, 142938.0, 144807.2759636195, 1025.8922813104516, 1032.5198782565992, 1084.9132069434427, 1104.9834252486219, 1106.0653747583278, 15743.370265189264, 18084.638307234043, 18635.254589816257, 18680.439586812416, 18710.438686839418, 51888.962220756126, 52998.0, 54611.180832288344, 54614.0, 55023.449765503436, 81129.0, 82573.58713206396, 83247.5837620808, 83293.0, 84194.0, 7865.095514015961, 7970.163132871197, 8043.276105150565, 8129.552874591717, 8314.376421768271, 10953.685557733123, 11354.659360219206, 11768.529258829893, 11852.881471185177, 12448.06639502059, 8348.666053357798, 10672.679819605424, 10734.73163170927, 10906.291091079054, 11358.864113588575, 10567.841482377617, 10586.0, 10633.787324253317, 10635.14918806509, 10838.187135964992, 9136.31477639193, 10769.676909692722, 11068.225224234226, 11219.382933938712, 11299.717507062389, 7907.288344049063, 8036.799080023046, 8055.718049868461, 8170.7957301067945, 8266.256036956704, 10194.286399952229, 10804.513796879002, 11069.5018724156, 11084.00243978046, 11089.944550277198], "value": 9569.30352696819, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.9290381932815, 1652.2151977810543, 1898.0699457265935, 1960.451073699363, 1970.2709997301001, 8240.175982401772, 8594.87107693385, 8845.955770221148, 9271.629134834608, 9299.628014879405, 6918.2389937107, 8724.47653140812, 8864.822703545926, 8867.68963086292, 8880.1563851434, 2440.524097800925, 2473.2332976777166, 2476.319012271622, 2477.578811602032, 2483.801295896333, 2421.624648179526, 2453.9754602453927, 2484.124486013058, 2486.27897909606, 2494.4886298308893, 2452.9264122076365, 2453.362125847275, 2466.802655787542, 2479.9008039678483, 2482.441450673593, 2400.9759902401015, 2419.2984034629935, 2432.914847980312, 2434.7808697217247, 2438.37821355555, 2410.457647029404, 2422.0917156066475, 2426.0538390027905, 2439.7682220189054, 2452.8773561321864, 8858.29133669305, 8859.911400886038, 8962.37263391563, 8967.865482017774, 8986.910130898737, 4771.665983381165, 5392.0, 5551.333839939192, 5551.528120109796, 5561.749721262553, 3158.652548219694, 3234.078287688021, 3507.736919731005, 3649.94525082124, 3701.7038636908983, 939.6523286384013, 954.746992047108, 1058.3215357047352, 1066.306900514664, 1067.3329169269207, 11101.44492775358, 11412.600558980395, 12006.279623222546, 12102.939485302517, 12245.938770306091, 3947.822347994347, 4738.218193998021, 4770.809167633256, 4801.231802911518, 4811.975940120347, 9509.762255943519, 9523.28575356851, 9533.570989305497, 9535.094166054214, 9544.570494327772, 5374.704391258519, 5408.1887716842, 5458.61789674719, 5458.781648734006, 5488.094464413327, 4562.954370456253, 4798.73606951621, 4973.602111831045, 4987.725675087905, 5099.0, 3900.356441187229, 3964.663003644665, 4019.9397009044887, 4037.9394309085387, 4066.1054567994993, 1251.8685538018558, 1253.467276407523, 1263.5577547858177, 1265.987340126587, 1269.9428525716364, 777.587878424436, 824.756696774453, 918.9081091890891, 947.9905200947902, 960.9231261499064, 5797.942020579824, 5874.73563689635, 5971.223740913653, 6083.239595050619, 6101.603395779349, 461.91450092282867, 463.74494028284306, 464.1436549566113, 471.4554689333825, 473.67789902866383, 3945.723799334019, 4025.4364388985564, 4026.57720939303, 4093.5701751315682, 4119.990602302455, 11112.999830015176, 11476.4835582399, 11520.481578328996, 11537.826932596017, 11694.94152529249, 11326.886731132749, 11468.426578671033, 11481.483333250024, 11491.827622585668, 11519.19365644397, 7359.896015597703, 7389.667464964197, 8079.4344395892895, 8567.18611731897, 8607.698730544402, 4788.880277993078, 5052.72210028452, 6145.354737752472, 6195.783147589993, 6391.169148010729, 8770.868436973324, 9901.50492475359, 10147.949260253652, 10406.947965260428, 10624.628138015132, 8970.730878073668, 10365.896341036492, 10517.579296828042, 10714.0, 10769.838452423062, 6754.324567543206, 7050.25972272904, 7779.961100194463, 7832.530048197126, 7927.4054445915635, 8616.827663446822, 8648.740537783877, 8709.259712924473, 8709.782255443411, 8713.25937295337, 6543.967280163569, 7436.256374362521, 7583.0, 7698.692052318069, 7850.568218748023, 7476.5887876167335, 7588.696452142073, 7592.16486186508, 7654.73208437702, 7698.307152356315, 7222.313880181375, 7290.307420795016, 7295.744648937261, 7325.4505912057875, 7339.816504587215, 4837.516248375135, 4915.950840491692, 5028.798848046184, 5078.644494885322, 5101.821436249714, 4939.185034469272, 4982.775775090202, 4998.2002879539095, 5022.447530771539, 5206.245094461357], "value": 4571.407631399959, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [245.39387712350495, 320.0462621388263, 321.7827966122868, 322.2154054876376, 326.6227507229151, 5429.212764149196, 5476.91784623231, 5514.255575497306, 5531.929778972669, 5551.361593416758, 4679.368285281486, 4808.567228949394, 4926.630502712293, 5156.613254005946, 5425.918611220825, 3927.7643341399607, 3957.643812056914, 4039.788063580929, 4104.302268614343, 4120.340745480724, 3415.7456078987875, 3822.4266360045935, 3934.6065393460567, 4136.793160341986, 4147.460830092099, 3682.5949145593963, 3948.348522493785, 3987.8604248851298, 4111.0, 4139.7930103494855, 3811.3701545933495, 3878.6897048236074, 4074.960884974327, 4117.341225403951, 4124.298869192245, 3272.8199949002797, 3496.8984769797435, 3534.9041797043, 3537.9739875436094, 3551.5738111426795, 5170.276161337416, 5205.349331333583, 5209.635325527214, 5213.7393130343335, 5225.947740522584, 4536.389581698482, 4717.858464246078, 4725.535084123898, 4758.262469317261, 4761.785719642625, 5656.575756818255, 5691.857703557403, 5745.942540574582, 5771.210924613383, 5815.127730840408, 3624.673779359844, 3753.474513568103, 3763.3414152523474, 3912.5304963404574, 3926.725129240954, 5396.974574830809, 5953.2856057272975, 6043.395660433922, 6076.726547305382, 6169.722362493699, 3932.7640341579595, 3950.881473555798, 3966.9801650991562, 3967.6429121379215, 4005.759654420744, 4039.313316736103, 4040.595940405936, 4042.5148982121746, 4048.9392659110135, 4052.9797351013644, 4004.65960393364, 4006.5192176938954, 4291.270484017662, 4671.182543054952, 4761.809607545407, 4620.930686039712, 4906.386701662293, 5356.3572371315695, 5572.9968605650565, 5815.621984570989, 1221.6579357779835, 1378.9175497234824, 1379.6688794689203, 1382.198324971514, 1382.377929931535, 835.6128826148564, 851.5018714052349, 855.840336344257, 863.1842908451579, 900.4867225681298, 4785.076237485298, 5068.553383699046, 5246.842594722164, 5301.125314323171, 5309.230161626542, 4832.574390554795, 5281.362777538975, 5532.336119665585, 5573.247611572467, 5870.26621672291, 4912.803487860517, 4963.404391473046, 5272.92090618641, 5275.657082289639, 5419.674819510842, 2783.6381270434713, 3096.969030309668, 3154.8422578870964, 3160.2731371784157, 3172.127664892127, 5821.554946922302, 5925.762969481172, 6011.579189456784, 6150.602319341603, 6188.195534580475, 5797.217375654317, 6063.423974722395, 6065.666388348595, 6099.725512351954, 6117.357677443804, 5640.915386269209, 6063.363346848518, 6084.269887613426, 6132.78535251264, 6212.751489940442, 4881.536254055859, 5295.735213239245, 5656.60403771732, 5863.970680146743, 5953.255843019622, 5821.79623713168, 5864.413558644102, 5883.352831188651, 5888.558358123072, 5888.673973780247, 5022.166909078087, 5237.921431178612, 5675.070476038167, 6045.576809623284, 6149.0776383542825, 3638.4900266389154, 3677.2093999790172, 3933.5476420212044, 3965.821538030738, 3979.7811120388656, 5491.560675146058, 5672.574556908331, 5961.642301461753, 5965.731542080704, 6014.518838492822, 4857.53853383928, 5212.5569326607665, 5537.9169312459535, 5753.928585709225, 5757.280339957505, 4986.681193245158, 5661.915071273852, 5713.428657134419, 5762.394948530344, 5787.55311172207, 5154.716490593053, 5383.9192412114635, 5591.944080559142, 5594.776208951596, 5711.485966263056, 5354.651947623392, 5590.170733047875, 5788.652680839163, 5800.0, 5813.081683044501, 5223.4776522347465, 5508.504234618904, 5553.722973580812, 5566.721663916707, 5773.826785196451], "value": 4165.481054023815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.5331920974527, 734.5702763883128, 751.9172890981993, 766.1534004924558, 782.5656760497922, 12017.0, 15026.549203523911, 15138.243087845618, 15202.315895784688, 15371.615709607251, 13404.809679254895, 14184.0, 14641.560753177395, 14688.0, 14723.779143312831, 695.408902432933, 889.9021107678183, 894.369469523984, 900.4642237868491, 913.2100732866089, 902.0979020979021, 908.9227415669643, 909.8999110097884, 914.5335878701843, 914.7758799094231, 970.5389939778619, 970.815545046439, 971.2181693736575, 971.6113554578154, 972.8540718892187, 954.6897258390982, 956.8564715292692, 956.9090936361034, 957.1720461800562, 958.4584709639066, 721.989170162448, 722.887952367384, 723.4284914917209, 726.458788202787, 726.5350175887436, 15146.621334466616, 15269.694606107816, 15283.312250948735, 15298.923505382512, 15307.311170997333, 12554.497820087277, 12793.0, 12888.742225155538, 12898.0, 13313.733725325439, 10169.084782369548, 10195.337303075275, 10246.0, 10289.813372491051, 10553.630622928238, 3493.8602455901987, 3497.300539892032, 3511.10466830959, 3536.504889315498, 3629.0, 65984.0, 85286.57356713177, 85602.57198714028, 89161.55419222928, 89563.0, 8298.377621678399, 9618.759531011641, 9642.566084526214, 9647.421154730739, 9980.500974951223, 22853.0, 22862.31413057611, 22867.97094130768, 22870.885645571667, 22914.312570622908, 7467.178610352857, 7523.924760752322, 7553.055868016498, 7661.693532258759, 7867.567283799446, 3849.191669749384, 3913.6477717005605, 4062.837486500566, 4064.9593504064583, 4081.0613558881623, 3096.551000104972, 3489.7208223342072, 3553.4136867416587, 3588.4258518636893, 3593.2454184621524, 540.3785646506549, 609.3395497269904, 623.329920335641, 632.0424556796457, 634.9746010159635, 486.0764547360044, 564.5398999815137, 579.6203486716207, 606.0575804623852, 613.6962203709103, 7368.410527157922, 8164.142765009708, 8231.588420578948, 8367.079621241659, 8409.285210757032, 542.7557599080429, 565.451512033327, 596.6658671144087, 604.0052479144473, 607.3622696169004, 3773.5471743390967, 3903.781164953677, 3931.4102884566973, 3965.266425711253, 4088.6729061674996, 88431.55784221167, 89656.55171724099, 90519.0, 91143.0, 99980.0, 77319.22680773232, 77510.6124469374, 78787.0, 86934.0, 87642.0, 70495.64752176206, 71476.64261678659, 73607.26392736111, 76291.0, 76516.0, 8296.70961516344, 8302.335813135056, 8369.874451883104, 8454.661813527637, 8566.914330856862, 40758.592414076666, 42219.36670949877, 43590.78204608957, 48188.75905620449, 48474.0, 62963.0, 65575.67212163909, 65625.68748624905, 65897.0, 66124.0, 10850.403227822546, 11006.416498139837, 11210.103191744804, 11333.093352531612, 11344.773104538028, 9793.706188814347, 11940.2835829847, 11991.100667450151, 12040.819387709016, 12145.21056131384, 11403.828942565702, 11417.257878237888, 11453.312801231952, 11458.885411146115, 11674.0, 11501.19491635611, 11559.711007224887, 11807.468663910317, 11877.52489900429, 11924.926756591949, 11272.774544509228, 11362.204645674723, 11413.086953043903, 11428.771424571629, 11661.24201926872, 8767.342449316198, 9404.905950940676, 9408.81182376335, 9665.27510436706, 9850.802983940137, 7514.812129696801, 8643.0, 8681.913180868363, 9164.083591640783, 9291.117343852324], "value": 6899.039459245691, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1616.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 106.0, 114.0, 115.0, 114.0, 105.0, 113.0, 125.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 120.0, 185.0, 209.0, 126.0, 168.0, 129.0, 143.0, 181.0, 146.0], "value": 159.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 217.0, 212.0, 195.0, 194.0, 190.0, 270.0, 271.0, 198.0], "value": 214.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 76.0, 177.0, 58.0, 67.0, 80.0, 91.0, 140.0, 59.0], "value": 90.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 144.0, 77.0, 80.0, 87.0, 122.0, 84.0, 83.0, 122.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 129.0, 88.0, 86.0, 88.0, 85.0, 81.0, 129.0, 118.0, 85.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 96.0, 106.0, 149.0, 101.0, 95.0, 96.0, 100.0, 94.0, 94.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 90.0, 126.0, 141.0, 91.0, 96.0, 90.0, 92.0, 93.0, 91.0], "value": 103.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 57.0, 60.0, 60.0, 59.0, 60.0, 117.0, 62.0, 59.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 71.0, 65.0, 75.0, 69.0, 67.0, 65.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 101.0, 106.0, 112.0, 105.0, 107.0, 84.0, 87.0, 86.0], "value": 95.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 136.0, 131.0, 136.0, 134.0, 154.0, 148.0, 220.0, 143.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 219.0, 215.0, 168.0, 210.0, 218.0, 180.0, 179.0, 221.0, 158.0], "value": 195.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 66.0, 63.0, 67.0, 64.0, 64.0, 65.0], "value": 65.2, "unit": "score"}]}]} 08:04:05 INFO - Return code: 0 08:04:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:04:05 ERROR - # TBPL SUCCESS # 08:04:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:04:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:04:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:04:05 INFO - 2016-05-17 08:04:05.497 screenresolution[1684:10750] starting screenresolution argv=screenresolution get 08:04:05 INFO - 2016-05-17 08:04:05.500 screenresolution[1684:10750] Display 0: 1600x1200x32@60 08:04:05 INFO - 2016-05-17 08:04:05.506 screenresolution[1685:10753] starting screenresolution argv=screenresolution list 08:04:05 INFO - Available Modes on Display 0 08:04:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:04:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:04:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:04:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:04:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:04:05 INFO - Intel Iris: 08:04:05 INFO - Chipset Model: Intel Iris 08:04:05 INFO - Type: GPU 08:04:05 INFO - Bus: Built-In 08:04:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:04:05 INFO - Vendor: Intel (0x8086) 08:04:05 INFO - Device ID: 0x0a2e 08:04:05 INFO - Revision ID: 0x0009 08:04:05 INFO - Displays: 08:04:05 INFO - Display: 08:04:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:04:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:04:05 INFO - Display Serial Number: 1600x1200 60 08:04:05 INFO - Main Display: Yes 08:04:05 INFO - Mirror: Off 08:04:05 INFO - Online: Yes 08:04:05 INFO - Rotation: Supported 08:04:05 INFO - Return code: 0 08:04:05 INFO - Running post-action listener: _resource_record_post_action 08:04:05 INFO - ##### 08:04:05 INFO - ##### Finished run-tests step (success) 08:04:05 INFO - ##### 08:04:05 INFO - Running post-run listener: _resource_record_post_run 08:04:06 WARNING - Exception when reporting resource usage: Traceback (most recent call last): 08:04:06 WARNING - File "/builds/slave/test/scripts/mozharness/base/python.py", line 507, in _resource_record_post_run 08:04:06 WARNING - self._resource_monitor.stop() 08:04:06 WARNING - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/mozsystemmonitor/resourcemonitor.py", line 325, in stop 08:04:06 WARNING - assert done 08:04:06 WARNING - AssertionError 08:04:06 INFO - Running post-run listener: _upload_blobber_files 08:04:06 INFO - Blob upload gear active. 08:04:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:04:06 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 08:04:06 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'] 08:04:06 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 08:04:07 INFO - (blobuploader) - INFO - Open directory for files ... 08:04:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:04:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:04:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:08 INFO - (blobuploader) - INFO - Done attempting. 08:04:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:04:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:04:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:09 INFO - (blobuploader) - INFO - Done attempting. 08:04:09 INFO - (blobuploader) - INFO - Iteration through files over. 08:04:09 INFO - Return code: 0 08:04:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:04:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:04:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb81a170ae6f9097822bbf26aaf9493b6475f340a92dc46aefa83dc31377dd828e3ef9f5d92be2694dc4d9fb8ed35c4e41459f337acb5c8616151941ac133f3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb68fc9647de3d39e04e410cc3906d97285c66d6c77ac71ec660534f6d6df10395690be28b068271d47fca4cb229ff9d0efd031a0a505db41cbc902eb0a25eb6"} 08:04:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:04:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:04:09 INFO - Contents: 08:04:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb81a170ae6f9097822bbf26aaf9493b6475f340a92dc46aefa83dc31377dd828e3ef9f5d92be2694dc4d9fb8ed35c4e41459f337acb5c8616151941ac133f3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb68fc9647de3d39e04e410cc3906d97285c66d6c77ac71ec660534f6d6df10395690be28b068271d47fca4cb229ff9d0efd031a0a505db41cbc902eb0a25eb6"} 08:04:09 INFO - Running post-run listener: copy_logs_to_upload_dir 08:04:09 INFO - Copying logs to upload dir... 08:04:09 INFO - mkdir: /builds/slave/test/build/upload/logs 08:04:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.904527 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-17 08:04:09.363686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 08:04:09.368882) ========= 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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb81a170ae6f9097822bbf26aaf9493b6475f340a92dc46aefa83dc31377dd828e3ef9f5d92be2694dc4d9fb8ed35c4e41459f337acb5c8616151941ac133f3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb68fc9647de3d39e04e410cc3906d97285c66d6c77ac71ec660534f6d6df10395690be28b068271d47fca4cb229ff9d0efd031a0a505db41cbc902eb0a25eb6"} build_url:https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011096 build_url: 'https://queue.taskcluster.net/v1/task/Ig7ggYxRSqOT4E8oVCTflA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb81a170ae6f9097822bbf26aaf9493b6475f340a92dc46aefa83dc31377dd828e3ef9f5d92be2694dc4d9fb8ed35c4e41459f337acb5c8616151941ac133f3b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb68fc9647de3d39e04e410cc3906d97285c66d6c77ac71ec660534f6d6df10395690be28b068271d47fca4cb229ff9d0efd031a0a505db41cbc902eb0a25eb6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 08:04:09.405162) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 08:04:09.405614) ========= 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.OaegpSLFUK/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.Af9ExCIDQ4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 08:04:09.454161) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 08:04:09.457683) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 08:04:09.458214) ========= ========= Total master_lag: 0.18 =========