builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0012 starttime: 1463010063.34 results: success (0) buildid: 20160511134706 builduid: 0931827588b74f4aa06eaa8feaf71ecf revision: 6afc5dab597975722542388390191a07ec0939d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.336628) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.337067) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.337371) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.356234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.356757) ========= 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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-11 16:41:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.40M=0.001s 2016-05-11 16:41:03 (8.40 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.535241) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.535588) ========= 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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.019970 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:03.583594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-11 16:41:03.583939) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6afc5dab597975722542388390191a07ec0939d5 --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 6afc5dab597975722542388390191a07ec0939d5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-11 16:41:03,659 truncating revision to first 12 chars 2016-05-11 16:41:03,659 Setting DEBUG logging. 2016-05-11 16:41:03,659 attempt 1/10 2016-05-11 16:41:03,659 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6afc5dab5979?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 16:41:05,809 unpacking tar archive at: fx-team-6afc5dab5979/testing/mozharness/ program finished with exit code 0 elapsedTime=2.576170 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-11 16:41:06.181154) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:06.181945) ========= script_repo_revision: 6afc5dab597975722542388390191a07ec0939d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:06.182889) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:06.183573) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 16:41:06.198251) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-11 16:41:06.198888) ========= /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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:06 INFO - MultiFileLogger online at 20160511 16:41:06 in /builds/slave/test 16:41:06 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 16:41:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:41:06 INFO - {'append_to_log': False, 16:41:06 INFO - 'base_work_dir': '/builds/slave/test', 16:41:06 INFO - 'blob_upload_branch': 'Fx-Team', 16:41:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:41:06 INFO - 'branch': 'Fx-Team', 16:41:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:41:06 INFO - 'config_files': ('talos/mac_config.py',), 16:41:06 INFO - 'default_actions': ('clobber', 16:41:06 INFO - 'read-buildbot-config', 16:41:06 INFO - 'download-and-extract', 16:41:06 INFO - 'populate-webroot', 16:41:06 INFO - 'create-virtualenv', 16:41:06 INFO - 'install', 16:41:06 INFO - 'run-tests'), 16:41:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:41:06 INFO - 'download_minidump_stackwalk': True, 16:41:06 INFO - 'download_symbols': 'ondemand', 16:41:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:41:06 INFO - 'tooltool.py': '/tools/tooltool.py', 16:41:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:41:06 INFO - '/tools/misc-python/virtualenv.py')}, 16:41:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:41:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:41:06 INFO - 'installer_path': 'installer.exe', 16:41:06 INFO - 'log_level': 'info', 16:41:06 INFO - 'log_name': 'talos', 16:41:06 INFO - 'log_to_console': True, 16:41:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:41:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:41:06 INFO - 'opt_config_files': (), 16:41:06 INFO - 'pip_index': False, 16:41:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:41:06 INFO - 'cmd': ('bash', 16:41:06 INFO - '-c', 16:41:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:41:06 INFO - 'enabled': True, 16:41:06 INFO - 'halt_on_failure': False, 16:41:06 INFO - 'name': 'check_screen_resolution'},), 16:41:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:41:06 INFO - 'cmd': ('bash', 16:41:06 INFO - '-c', 16:41:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:41:06 INFO - 'enabled': True, 16:41:06 INFO - 'halt_on_failure': False, 16:41:06 INFO - 'name': 'check_screen_resolution'},), 16:41:06 INFO - 'run_cmd_checks_enabled': True, 16:41:06 INFO - 'sps_profile': False, 16:41:06 INFO - 'sps_profile_interval': 0, 16:41:06 INFO - 'suite': 'dromaeojs-e10s', 16:41:06 INFO - 'system_bits': '32', 16:41:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:41:06 INFO - 'title': 't-yosemite-r7-0012', 16:41:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:41:06 INFO - 'use_talos_json': True, 16:41:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:41:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:41:06 INFO - 'work_dir': 'build'} 16:41:06 INFO - ##### 16:41:06 INFO - ##### Running clobber step. 16:41:06 INFO - ##### 16:41:06 INFO - Running pre-action listener: _resource_record_pre_action 16:41:06 INFO - Running main action method: clobber 16:41:06 INFO - rmtree: /builds/slave/test/build 16:41:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:41:07 INFO - Running post-action listener: _resource_record_post_action 16:41:07 INFO - ##### 16:41:07 INFO - ##### Finished clobber step (success) 16:41:07 INFO - ##### 16:41:07 INFO - ##### 16:41:07 INFO - ##### Running read-buildbot-config step. 16:41:07 INFO - ##### 16:41:07 INFO - Running pre-action listener: _resource_record_pre_action 16:41:07 INFO - Running main action method: read_buildbot_config 16:41:07 INFO - Using buildbot properties: 16:41:07 INFO - { 16:41:07 INFO - "project": "", 16:41:07 INFO - "product": "firefox", 16:41:07 INFO - "script_repo_revision": "production", 16:41:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 16:41:07 INFO - "repository": "", 16:41:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 16:41:07 INFO - "buildid": "20160511134706", 16:41:07 INFO - "pgo_build": "False", 16:41:07 INFO - "basedir": "/builds/slave/test", 16:41:07 INFO - "buildnumber": 7, 16:41:07 INFO - "slavename": "t-yosemite-r7-0012", 16:41:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:41:07 INFO - "platform": "yosemite_r7", 16:41:07 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 16:41:07 INFO - "branch": "fx-team", 16:41:07 INFO - "repo_path": "integration/fx-team", 16:41:07 INFO - "revision": "6afc5dab597975722542388390191a07ec0939d5", 16:41:07 INFO - "stage_platform": "macosx64", 16:41:07 INFO - "builduid": "0931827588b74f4aa06eaa8feaf71ecf", 16:41:07 INFO - "slavebuilddir": "test" 16:41:07 INFO - } 16:41:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:41:07 INFO - Running post-action listener: _resource_record_post_action 16:41:07 INFO - ##### 16:41:07 INFO - ##### Finished read-buildbot-config step (success) 16:41:07 INFO - ##### 16:41:07 INFO - ##### 16:41:07 INFO - ##### Running download-and-extract step. 16:41:07 INFO - ##### 16:41:07 INFO - Running pre-action listener: _resource_record_pre_action 16:41:07 INFO - Running main action method: download_and_extract 16:41:07 INFO - mkdir: /builds/slave/test/build/tests 16:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:07 INFO - https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:41:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:41:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:41:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:08 INFO - Downloaded 1199 bytes. 16:41:08 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:41:08 INFO - Using the following test package requirements: 16:41:08 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:41:08 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:41:08 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:41:08 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'jsshell-mac.zip'], 16:41:08 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:41:08 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:41:08 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:41:08 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:41:08 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:41:08 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:41:08 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:41:08 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:08 INFO - https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:41:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:41:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:41:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:10 INFO - Downloaded 18150582 bytes. 16:41: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'] 16:41: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 16:41:13 INFO - Return code: 0 16:41:13 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 16:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:13 INFO - https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:41:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:41:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:41:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:14 INFO - Downloaded 18150582 bytes. 16:41:14 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'] 16:41:14 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 16:41:17 INFO - Return code: 0 16:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:17 INFO - https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:41:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:41:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:41:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:18 INFO - Downloaded 10840152 bytes. 16:41:18 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'] 16:41:18 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 16:41:19 INFO - Return code: 0 16:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:19 INFO - https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:41:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:41:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:41:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:41:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Oqt585BfTaWUT9HWYaoxag/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 16:41:22 INFO - Downloaded 109800082 bytes. 16:41:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:41:22 INFO - mkdir: /builds/slave/test/properties 16:41:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:41:22 INFO - Writing to file /builds/slave/test/properties/build_url 16:41:22 INFO - Contents: 16:41:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:41:22 INFO - Running post-action listener: _resource_record_post_action 16:41:22 INFO - Running post-action listener: set_extra_try_arguments 16:41:22 INFO - ##### 16:41:22 INFO - ##### Finished download-and-extract step (success) 16:41:22 INFO - ##### 16:41:22 INFO - ##### 16:41:22 INFO - ##### Running populate-webroot step. 16:41:22 INFO - ##### 16:41:22 INFO - Running pre-action listener: _resource_record_pre_action 16:41:22 INFO - Running main action method: populate_webroot 16:41:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:41:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:41:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:41:22 INFO - '--tppagecycles', 16:41:22 INFO - '7'], 16:41:22 INFO - 'tests': ['tsvgm']}}, 16:41:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:41:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:41:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:41:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:41:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:41:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:41:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['damp', 'tps']}, 16:41:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'tests': ['damp', 'tps']}, 16:41:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['dromaeo_dom']}, 16:41:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:41:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other-e10s': {'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['a11yr', 16:41:22 INFO - 'ts_paint', 16:41:22 INFO - 'tpaint', 16:41:22 INFO - 'sessionrestore', 16:41:22 INFO - 'sessionrestore_no_auto_restore', 16:41:22 INFO - 'tabpaint']}, 16:41:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tsvgx', 16:41:22 INFO - 'tsvgr_opacity', 16:41:22 INFO - 'tart', 16:41:22 INFO - 'tscrollx', 16:41:22 INFO - 'cart']}, 16:41:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:41:22 INFO - 'tsvgr_opacity', 16:41:22 INFO - 'tart', 16:41:22 INFO - 'tscrollx', 16:41:22 INFO - 'cart']}, 16:41:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s'], 16:41:22 INFO - 'tests': ['tp5o']}, 16:41:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'tests': ['tp5o']}, 16:41:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--disable-e10s', 16:41:22 INFO - '--xperf_path', 16:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:41:22 INFO - 'tests': ['tp5n']}, 16:41:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:22 INFO - 'talos_options': ['--xperf_path', 16:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:41:22 INFO - 'tests': ['tp5n']}}, 16:41:22 INFO - 'talos.zip': {'path': '', 16:41:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:41:22 INFO - Running post-action listener: _resource_record_post_action 16:41:22 INFO - ##### 16:41:22 INFO - ##### Finished populate-webroot step (success) 16:41:22 INFO - ##### 16:41:22 INFO - ##### 16:41:22 INFO - ##### Running create-virtualenv step. 16:41:22 INFO - ##### 16:41:22 INFO - Running pre-action listener: _resource_record_pre_action 16:41:22 INFO - Running main action method: create_virtualenv 16:41:22 INFO - Creating virtualenv /builds/slave/test/build/venv 16:41:22 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 16:41:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:41:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:41:22 INFO - Using real prefix '/tools/python27' 16:41:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:41:23 INFO - Installing distribute.............................................................................................................................................................................................done. 16:41:26 INFO - Installing pip.................done. 16:41:26 INFO - Return code: 0 16:41:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:41:26 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')]} 16:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:26 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')]} 16:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:26 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 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:26 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 16:41:26 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 16:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:26 INFO - 'HOME': '/Users/cltbld', 16:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:26 INFO - 'LOGNAME': 'cltbld', 16:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:26 INFO - 'MOZ_NO_REMOTE': '1', 16:41:26 INFO - 'NO_EM_RESTART': '1', 16:41:26 INFO - 'PAGER': '/bin/cat', 16:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:26 INFO - 'PWD': '/builds/slave/test', 16:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:26 INFO - 'SHELL': '/bin/bash', 16:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:26 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:26 INFO - 'USER': 'cltbld', 16:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:26 INFO - 'XPC_FLAGS': '0x0', 16:41:26 INFO - 'XPC_SERVICE_NAME': '0', 16:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:26 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 16:41:26 INFO - Cleaning up... 16:41:26 INFO - Return code: 0 16:41:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:41:26 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')]} 16:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:26 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')]} 16:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 16:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:26 INFO - 'HOME': '/Users/cltbld', 16:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:26 INFO - 'LOGNAME': 'cltbld', 16:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:26 INFO - 'MOZ_NO_REMOTE': '1', 16:41:26 INFO - 'NO_EM_RESTART': '1', 16:41:26 INFO - 'PAGER': '/bin/cat', 16:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:26 INFO - 'PWD': '/builds/slave/test', 16:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:26 INFO - 'SHELL': '/bin/bash', 16:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:26 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:26 INFO - 'USER': 'cltbld', 16:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:26 INFO - 'XPC_FLAGS': '0x0', 16:41:26 INFO - 'XPC_SERVICE_NAME': '0', 16:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:27 INFO - Downloading/unpacking psutil>=0.7.1 16:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:41:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:30 INFO - Installing collected packages: psutil 16:41:30 INFO - Running setup.py install for psutil 16:41:30 INFO - building 'psutil._psutil_osx' extension 16:41:30 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 16:41:30 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 16:41:30 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 16:41:30 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 16:41:30 INFO - building 'psutil._psutil_posix' extension 16:41:30 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 16:41:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:41:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:41:30 INFO - ^ 16:41:30 INFO - 1 warning generated. 16:41:30 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 16:41:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:30 INFO - Successfully installed psutil 16:41:30 INFO - Cleaning up... 16:41:30 INFO - Return code: 0 16:41:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:41:30 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')]} 16:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:30 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')]} 16:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:30 INFO - 'HOME': '/Users/cltbld', 16:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:30 INFO - 'LOGNAME': 'cltbld', 16:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:30 INFO - 'MOZ_NO_REMOTE': '1', 16:41:30 INFO - 'NO_EM_RESTART': '1', 16:41:30 INFO - 'PAGER': '/bin/cat', 16:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:30 INFO - 'PWD': '/builds/slave/test', 16:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:30 INFO - 'SHELL': '/bin/bash', 16:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:30 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:30 INFO - 'USER': 'cltbld', 16:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:30 INFO - 'XPC_FLAGS': '0x0', 16:41:30 INFO - 'XPC_SERVICE_NAME': '0', 16:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:41:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:41:33 INFO - Installing collected packages: mozsystemmonitor 16:41:33 INFO - Running setup.py install for mozsystemmonitor 16:41:33 INFO - Successfully installed mozsystemmonitor 16:41:33 INFO - Cleaning up... 16:41:33 INFO - Return code: 0 16:41:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:33 INFO - 'HOME': '/Users/cltbld', 16:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:33 INFO - 'LOGNAME': 'cltbld', 16:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:33 INFO - 'MOZ_NO_REMOTE': '1', 16:41:33 INFO - 'NO_EM_RESTART': '1', 16:41:33 INFO - 'PAGER': '/bin/cat', 16:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:33 INFO - 'PWD': '/builds/slave/test', 16:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:33 INFO - 'SHELL': '/bin/bash', 16:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:33 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:33 INFO - 'USER': 'cltbld', 16:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:33 INFO - 'XPC_FLAGS': '0x0', 16:41:33 INFO - 'XPC_SERVICE_NAME': '0', 16:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:34 INFO - Downloading/unpacking blobuploader==1.2.4 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:36 INFO - Downloading blobuploader-1.2.4.tar.gz 16:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:41:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:41:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:37 INFO - Downloading docopt-0.6.1.tar.gz 16:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:41:37 INFO - Installing collected packages: blobuploader, requests, docopt 16:41:37 INFO - Running setup.py install for blobuploader 16:41:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:41:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:41:37 INFO - Running setup.py install for requests 16:41:38 INFO - Running setup.py install for docopt 16:41:38 INFO - Successfully installed blobuploader requests docopt 16:41:38 INFO - Cleaning up... 16:41:38 INFO - Return code: 0 16:41:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:38 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 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:38 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 16:41:38 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 16:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:38 INFO - 'HOME': '/Users/cltbld', 16:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:38 INFO - 'LOGNAME': 'cltbld', 16:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:38 INFO - 'MOZ_NO_REMOTE': '1', 16:41:38 INFO - 'NO_EM_RESTART': '1', 16:41:38 INFO - 'PAGER': '/bin/cat', 16:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:38 INFO - 'PWD': '/builds/slave/test', 16:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:38 INFO - 'SHELL': '/bin/bash', 16:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:38 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:38 INFO - 'USER': 'cltbld', 16:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:38 INFO - 'XPC_FLAGS': '0x0', 16:41:38 INFO - 'XPC_SERVICE_NAME': '0', 16:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-NiV6r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-RGikAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-o5ru0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Z6WcOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-UkM6wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-kch7te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-SmEbYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Md6R9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-_ggY17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:39 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-1RJBkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:40 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-yd22T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:40 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-5m0srm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:40 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-maZ1C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:40 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-2Qv8eT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:40 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-OT4Pdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:40 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-JPuGKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:40 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-bthpzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:41:40 INFO - Running setup.py install for manifestparser 16:41:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Running setup.py install for mozcrash 16:41:41 INFO - Running setup.py install for mozdebug 16:41:41 INFO - Running setup.py install for mozdevice 16:41:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for mozfile 16:41:41 INFO - Running setup.py install for mozhttpd 16:41:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for mozinfo 16:41:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for mozInstall 16:41:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for mozleak 16:41:42 INFO - Running setup.py install for mozlog 16:41:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:41:42 INFO - Running setup.py install for moznetwork 16:41:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:41:42 INFO - Running setup.py install for mozprocess 16:41:42 INFO - Running setup.py install for mozprofile 16:41:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:41:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:41:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:41:42 INFO - Running setup.py install for mozrunner 16:41:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:41:42 INFO - Running setup.py install for mozscreenshot 16:41:43 INFO - Running setup.py install for moztest 16:41:43 INFO - Running setup.py install for mozversion 16:41:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:41:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:41:43 INFO - Cleaning up... 16:41:43 INFO - Return code: 0 16:41:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:43 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')]} 16:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:43 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')]} 16:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:43 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 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:43 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 16:41:43 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 16:41:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:43 INFO - 'HOME': '/Users/cltbld', 16:41:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:43 INFO - 'LOGNAME': 'cltbld', 16:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:43 INFO - 'MOZ_NO_REMOTE': '1', 16:41:43 INFO - 'NO_EM_RESTART': '1', 16:41:43 INFO - 'PAGER': '/bin/cat', 16:41:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:43 INFO - 'PWD': '/builds/slave/test', 16:41:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:43 INFO - 'SHELL': '/bin/bash', 16:41:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:43 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:43 INFO - 'USER': 'cltbld', 16:41:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:43 INFO - 'XPC_FLAGS': '0x0', 16:41:43 INFO - 'XPC_SERVICE_NAME': '0', 16:41:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:43 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-2TnEhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:43 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)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:43 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Qqot1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:43 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)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:43 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-s_wHtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:43 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)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:43 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-WbTZpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-cXBaA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-fcmDnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-yZqt8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-5UaFhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-5iL4aQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-dGWl7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-m3Z8Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ihqs33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:44 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)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:44 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-m6WoFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:45 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)) 16:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-i3tHU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:45 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)) 16:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-xntwkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:45 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)) 16:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-RStlvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:45 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)) 16:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:45 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-EwHK_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:45 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)) 16:41:45 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)) 16:41:45 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)) 16:41:45 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)) 16:41:45 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)) 16:41:45 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)) 16:41:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:45 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 16:41:47 INFO - Downloading blessings-1.6.tar.gz 16:41:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:41:47 INFO - Installing collected packages: blessings 16:41:47 INFO - Running setup.py install for blessings 16:41:48 INFO - Successfully installed blessings 16:41:48 INFO - Cleaning up... 16:41:48 INFO - Return code: 0 16:41:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:41:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:48 INFO - Reading from file tmpfile_stdout 16:41:48 INFO - Current package versions: 16:41:48 INFO - blessings == 1.6 16:41:48 INFO - blobuploader == 1.2.4 16:41:48 INFO - docopt == 0.6.1 16:41:48 INFO - manifestparser == 1.1 16:41:48 INFO - mozInstall == 1.12 16:41:48 INFO - mozcrash == 0.17 16:41:48 INFO - mozdebug == 0.1 16:41:48 INFO - mozdevice == 0.48 16:41:48 INFO - mozfile == 1.2 16:41:48 INFO - mozhttpd == 0.7 16:41:48 INFO - mozinfo == 0.9 16:41:48 INFO - mozleak == 0.1 16:41:48 INFO - mozlog == 3.1 16:41:48 INFO - moznetwork == 0.27 16:41:48 INFO - mozprocess == 0.22 16:41:48 INFO - mozprofile == 0.28 16:41:48 INFO - mozrunner == 6.11 16:41:48 INFO - mozscreenshot == 0.1 16:41:48 INFO - mozsystemmonitor == 0.0 16:41:48 INFO - moztest == 0.7 16:41:48 INFO - mozversion == 1.4 16:41:48 INFO - psutil == 3.1.1 16:41:48 INFO - requests == 1.2.3 16:41:48 INFO - wsgiref == 0.1.2 16:41:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:48 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')]} 16:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:48 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')]} 16:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:48 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 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:48 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 16:41:48 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 16:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:48 INFO - 'HOME': '/Users/cltbld', 16:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:48 INFO - 'LOGNAME': 'cltbld', 16:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:48 INFO - 'MOZ_NO_REMOTE': '1', 16:41:48 INFO - 'NO_EM_RESTART': '1', 16:41:48 INFO - 'PAGER': '/bin/cat', 16:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:48 INFO - 'PWD': '/builds/slave/test', 16:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:48 INFO - 'SHELL': '/bin/bash', 16:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:48 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:48 INFO - 'USER': 'cltbld', 16:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:48 INFO - 'XPC_FLAGS': '0x0', 16:41:48 INFO - 'XPC_SERVICE_NAME': '0', 16:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 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)) 16:41:48 INFO - Cleaning up... 16:41:48 INFO - Return code: 0 16:41:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:41:48 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')]} 16:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:48 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')]} 16:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:48 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 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:48 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 16:41:48 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 16:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:48 INFO - 'HOME': '/Users/cltbld', 16:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:48 INFO - 'LOGNAME': 'cltbld', 16:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:48 INFO - 'MOZ_NO_REMOTE': '1', 16:41:48 INFO - 'NO_EM_RESTART': '1', 16:41:48 INFO - 'PAGER': '/bin/cat', 16:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:48 INFO - 'PWD': '/builds/slave/test', 16:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:48 INFO - 'SHELL': '/bin/bash', 16:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:48 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:48 INFO - 'USER': 'cltbld', 16:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:48 INFO - 'XPC_FLAGS': '0x0', 16:41:48 INFO - 'XPC_SERVICE_NAME': '0', 16:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:49 INFO - Downloading/unpacking jsonschema 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:41:51 INFO - Downloading/unpacking functools32 (from jsonschema) 16:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:51 INFO - Downloading functools32-3.2.3-2.tar.gz 16:41:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:41:51 INFO - warning: no files found matching '*.txt' 16:41:51 INFO - no previously-included directories found matching 'build' 16:41:51 INFO - no previously-included directories found matching 'dist' 16:41:51 INFO - no previously-included directories found matching '.git*' 16:41:51 INFO - Installing collected packages: jsonschema, functools32 16:41:52 INFO - Running setup.py install for functools32 16:41:52 INFO - warning: no files found matching '*.txt' 16:41:52 INFO - no previously-included directories found matching 'build' 16:41:52 INFO - no previously-included directories found matching 'dist' 16:41:52 INFO - no previously-included directories found matching '.git*' 16:41:52 INFO - Successfully installed jsonschema functools32 16:41:52 INFO - Cleaning up... 16:41:52 INFO - Return code: 0 16:41:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:52 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 0x10ab0ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab11570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb28486d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, '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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 16:41:52 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 16:41:52 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 16:41:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:41:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:52 INFO - 'HOME': '/Users/cltbld', 16:41:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:52 INFO - 'LOGNAME': 'cltbld', 16:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:52 INFO - 'MOZ_NO_REMOTE': '1', 16:41:52 INFO - 'NO_EM_RESTART': '1', 16:41:52 INFO - 'PAGER': '/bin/cat', 16:41:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:52 INFO - 'PWD': '/builds/slave/test', 16:41:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:52 INFO - 'SHELL': '/bin/bash', 16:41:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:41:52 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:41:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:52 INFO - 'USER': 'cltbld', 16:41:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:52 INFO - 'XPC_FLAGS': '0x0', 16:41:52 INFO - 'XPC_SERVICE_NAME': '0', 16:41:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:52 INFO - Downloading/unpacking flake8 16:41:52 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 16:41:52 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 16:41:52 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 16:41:52 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 16:41:52 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 16:41:52 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 16:41:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:41:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:41:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:41:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:41:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:41:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:41:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:41:56 INFO - no previously-included directories found matching 'docs/_build' 16:41:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:41:56 INFO - Running setup.py install for pep8 16:41:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:41:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:41:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:41:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:41:56 INFO - no previously-included directories found matching 'docs/_build' 16:41:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:41:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:41:56 INFO - Cleaning up... 16:41:56 INFO - Return code: 0 16:41:56 INFO - Running post-action listener: _resource_record_post_action 16:41:56 INFO - Running post-action listener: _start_resource_monitoring 16:41:56 INFO - Starting resource monitoring. 16:41:56 INFO - ##### 16:41:56 INFO - ##### Finished create-virtualenv step (success) 16:41:56 INFO - ##### 16:41:56 INFO - ##### 16:41:56 INFO - ##### Running install step. 16:41:56 INFO - ##### 16:41:56 INFO - Running pre-action listener: _resource_record_pre_action 16:41:56 INFO - Running main action method: install 16:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:56 INFO - Reading from file tmpfile_stdout 16:41:56 INFO - Detecting whether we're running mozinstall >=1.0... 16:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:41:56 INFO - Reading from file tmpfile_stdout 16:41:56 INFO - Output received: 16:41:56 INFO - Usage: mozinstall [options] installer 16:41:56 INFO - Options: 16:41:56 INFO - -h, --help show this help message and exit 16:41:56 INFO - -d DEST, --destination=DEST 16:41:56 INFO - Directory to install application into. [default: 16:41:56 INFO - "/builds/slave/test"] 16:41:56 INFO - --app=APP Application being installed. [default: firefox] 16:41:56 INFO - mkdir: /builds/slave/test/build/application 16:41:56 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'] 16:41:56 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 16:42:31 INFO - Reading from file tmpfile_stdout 16:42:31 INFO - Output received: 16:42:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:42:31 INFO - Running post-action listener: _resource_record_post_action 16:42:31 INFO - ##### 16:42:31 INFO - ##### Finished install step (success) 16:42:31 INFO - ##### 16:42:31 INFO - ##### 16:42:31 INFO - ##### Running run-tests step. 16:42:31 INFO - ##### 16:42:31 INFO - Running pre-action listener: _resource_record_pre_action 16:42:31 INFO - Running main action method: run_tests 16:42:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:42:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:42:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:42:31 INFO - 2016-05-11 16:42:31.092 screenresolution[1933:12240] starting screenresolution argv=screenresolution get 16:42:31 INFO - 2016-05-11 16:42:31.095 screenresolution[1933:12240] Display 0: 1600x1200x32@60 16:42:31 INFO - 2016-05-11 16:42:31.101 screenresolution[1934:12242] starting screenresolution argv=screenresolution list 16:42:31 INFO - Available Modes on Display 0 16:42:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:42:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:42:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:42:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:42:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:42:31 INFO - Intel Iris: 16:42:31 INFO - Chipset Model: Intel Iris 16:42:31 INFO - Type: GPU 16:42:31 INFO - Bus: Built-In 16:42:31 INFO - VRAM (Dynamic, Max): 1536 MB 16:42:31 INFO - Vendor: Intel (0x8086) 16:42:31 INFO - Device ID: 0x0a2e 16:42:31 INFO - Revision ID: 0x0009 16:42:31 INFO - Displays: 16:42:31 INFO - Display: 16:42:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:42:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:42:31 INFO - Display Serial Number: 1600x1200 60 16:42:31 INFO - Main Display: Yes 16:42:31 INFO - Mirror: Off 16:42:31 INFO - Online: Yes 16:42:31 INFO - Rotation: Supported 16:42:31 INFO - Return code: 0 16:42:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:42:31 INFO - Python 2.7.3 16:42:31 INFO - Return code: 0 16:42:31 INFO - grabbing minidump binary from tooltool 16:42: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')]} 16:42:31 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 0x10a9afd30>, '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 0x10a7d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:42:31 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 16:42:31 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 16:42:31 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 16:42:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:42:31 INFO - Return code: 0 16:42:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:42:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:42:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:42:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:42:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:42:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:42:32 INFO - Return code: 0 16:42:32 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/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 16:42:32 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/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --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 16:42:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NoI2LGOQ3u/Render', 16:42:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:32 INFO - 'HOME': '/Users/cltbld', 16:42:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:32 INFO - 'LOGNAME': 'cltbld', 16:42:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:42:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:42:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:32 INFO - 'MOZ_NO_REMOTE': '1', 16:42:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:42:32 INFO - 'NO_EM_RESTART': '1', 16:42:32 INFO - 'PAGER': '/bin/cat', 16:42:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:32 INFO - 'PWD': '/builds/slave/test', 16:42:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:42:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:32 INFO - 'SHELL': '/bin/bash', 16:42:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZWHscsAKcs/Listeners', 16:42:32 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 16:42:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:32 INFO - 'USER': 'cltbld', 16:42:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:32 INFO - 'XPC_FLAGS': '0x0', 16:42:32 INFO - 'XPC_SERVICE_NAME': '0', 16:42:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:32 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/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 16:42:32 INFO - mozversion application_buildid: 20160511134706 16:42:32 INFO - mozversion application_changeset: 6afc5dab597975722542388390191a07ec0939d5 16:42:32 INFO - mozversion application_display_name: Nightly 16:42:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:42:32 INFO - mozversion application_name: Firefox 16:42:32 INFO - mozversion application_remotingname: firefox 16:42:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:42:32 INFO - mozversion application_vendor: Mozilla 16:42:32 INFO - mozversion application_version: 49.0a1 16:42:32 INFO - mozversion platform_buildid: 20160511134706 16:42:32 INFO - mozversion platform_changeset: 6afc5dab597975722542388390191a07ec0939d5 16:42:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:42:32 INFO - mozversion platform_version: 49.0a1 16:42:32 INFO - using testdate: 1463010152 16:42:32 INFO - actual date: 1463010152 16:42:32 INFO - starting webserver on 'localhost:51999' 16:42:32 INFO - SUITE-START | Running 2 tests 16:42:32 INFO - TEST-START | dromaeo_css 16:42:32 INFO - operating with platform_type : mac_ 16:42:32 INFO - Initialising browser for dromaeo_css test... 16:42:32 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpZ5zHOO/profile http://localhost:51999/getInfo.html) 16:42:35 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 16:42:35 INFO - PROCESS | 1952 | colorDepth:24 16:42:35 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 16:42:35 INFO - PROCESS | 1952 | __metrics 16:42:35 ERROR - PROCESS | 1952 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 16:42:36 INFO - TEST-INFO | 1952: exit 0 16:42:36 INFO - Browser initialized. 16:42:36 INFO - Running cycle 1/1 for dromaeo_css test... 16:42:36 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpZ5zHOO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:42:48 INFO - PROCESS | 1955 | RSS: Main: 250097664 16:42:48 INFO - PROCESS | 1955 | 16:43:38 INFO - PROCESS | 1955 | 1463010218078 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] 16:45:49 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51999/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51999/tests/dromaeo/cssquery-ext.html) 16:45:50 INFO - PROCESS | 1955 | RSS: Main: 261971968 16:45:50 INFO - PROCESS | 1955 | 16:48:53 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51999/tests/dromaeo/cssquery-ext.html (next: http://localhost:51999/tests/dromaeo/cssquery-jquery.html) 16:48:53 INFO - PROCESS | 1955 | RSS: Main: 273629184 16:48:53 INFO - PROCESS | 1955 | 16:51:55 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51999/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51999/tests/dromaeo/cssquery-mootools.html) 16:51:56 INFO - PROCESS | 1955 | RSS: Main: 275636224 16:51:56 INFO - PROCESS | 1955 | 16:54:57 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51999/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51999/tests/dromaeo/cssquery-prototype.html) 16:54:58 INFO - PROCESS | 1955 | RSS: Main: 277053440 16:54:58 INFO - PROCESS | 1955 | 16:58:01 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51999/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51999/tests/dromaeo/cssquery-yui.html) 16:58:01 INFO - PROCESS | 1955 | RSS: Main: 280256512 16:58:01 INFO - PROCESS | 1955 | 17:01:04 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51999/tests/dromaeo/cssquery-yui.html (next: http://localhost:51999/tests/dromaeo/cssquery-dojo.html) 17:01:05 INFO - PROCESS | 1955 | RSS: Main: 281239552 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | __start_tp_report 17:01:05 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 17:01:05 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 17:01:05 INFO - PROCESS | 1955 | |i|pagename|runs| 17:01:05 INFO - PROCESS | 1955 | |0;dojo.html;3483.2859263850905;3516.1209697575605;3518.050126465854;3524.806135662539;3565.483004964282;4691;5879.823605291843;5944.910826337608;5969.194158788568;5969.820905372835;5751.856203594913;5889.293284805819;5902.911456328158;5970.492508136814;6019.849503762408;4668.229742092551;5254.947450525503;5302.92045619316;5361.919571206435;5368.785248590071;262.64637873252593;5304.018756530035;5321.175217841241;5351.866203344928;5363.168708850115;5309.893802123975;5317.255584218232;5323.361196656407;5324.281222035014;5336.30628018359;5325.680459172462;5339.492748188916;5351.143816989302;5386.48828361305;5398.541124004464;4990.30135780991;5220.504052115043;5260.210968354739;5261.684298942076;5338.599605029638;5630.521405680522;5749.252597162384;5792.594518383715;5824.3302020267365;5845.269341332333;5127.871803204913;5974.97012514939;6035.702324000361;6241.313455519889;6315.494760419201;5702.486776190163;6273.843153921142;6275.309715931244;6348.619082854997;6358.395952384516;4559.384483094773;5473.616846820724;5553.527950124244;6053.1828203192445;6056.242969628796;6222;6306.9684651576445;6419.069234960904;6467.094606755058;6491.51313651478;6142.590862071504;6171.7531298748445;6185;6190.969045154746;6202.472789812826;5276.683398996072;6273.3099359071375;6293.653849038347;6336.52476064297;6345.619262844244;6152.415520525543;6176.245819149088;6218.751249949952;6245.406686364788;6252.187215661935;6276.121343011982;6293.96853015732;6312.558120931582;6315.115883776275;6358.8410289742205;5037.798488060436;5092.439831618519;5098.5411312982005;5100.362454693163;5108.872278193075;4349.217140914666;4371.213181627274;4373.2565463871215;4424.557544245549;4432.844850430284;5946.910796338058;5974.074018527135;6011.729472173763;6077.817665470043;6079.300880398722;6360.265534358026;6365.713542890581;6373.52198585108;6397.584157029872;6404;5840.211571437886;5962.791302304398;5976.073708575178;6008.459238668541;6025.367336429699;5551.111822108524;5573.944260557424;5578.995780759421;5587.217789509472;5588.469095435927;6200.689965501797;6587.407133357925;6591.307912669101;6593.044008618627;6618;6253.2496100466415;6292.465140463112;6319.526035547445;6338.90491642635;6356.0465930109;6505.902411463737;6536.869262614626;6543.345665433419;6546.410823025951;6570.5729127606555;5492.038893193755;6067.726952287246;6208.81373558794;6239;6324.209473815773;5577.888442231214;6197.225346831646;6258.655773932477;6365.490760739222;6419.2938776735455;6271.278802937721;6325.209348831396;6335.4931605472375;6340.048992651139;6346.777862774781;6132.661461321519;6135.171422439487;6161.383861613803;6316.431521163117;6322.747090116344;6012.293125397822;6153.046277826944;6171.321154672893;6180.5673602849165;6205.100260462298;6314.273858506331;6316.62100273985;6328.303886572381;6342.52431067681;6347.96826015867;6051.667158306335;6118.345080237214;6149.8007888461325;6164.321924588381;6197.876042479215 17:01:05 INFO - PROCESS | 1955 | |1;ext.html;498.2128237384932;503.67009608706286;515.8271978887072;531.4685314685315;536.6806749983758;34083.4887476688;34403.13992150198;39216.41175382368;41969.37045944313;41984.58015419845;34116.48825267623;42804.57195428045;42825.14349713004;42850.571494285046;43041;8319.916800831974;9520.952695203521;9792.853107203398;9801.16690081344;9854.113129818315;9480.81038379232;9525.999770024151;9633.27750418718;9723.513824308757;9774.16919561838;8515.872261916076;9688.321817472779;9812.969638187995;9822.950885245564;9835.65575204868;7848.372130229568;9790.971947945434;9806.166475849597;9811.11699947001;9811.90188098117;9571.042895710443;9640.180584650314;9649.710508684751;9662.516874156334;9666.275029372755;39281.803590981865;39662.40506392407;39685.60314396848;39741.80774576767;39889;2787.3171073087224;3061.188784971962;3121.9219519511967;3205.7114859662743;3464.22055037617;29301.853490732625;29536.70463295361;29822.403551929056;30598.082057538308;30748.8462557688;2714.2128782653203;2780.179846945156;2780.9860950695115;2788.623535822658;2828.109145619144;483633;491503;497462.9999999964;498143;499646;69781.95327070098;70199.29800701927;70335.64832175807;70507;70512.64743676351;38302;38988.80505597454;39265.80367098146;40419.98950026273;40567.79716101401;17401;17502.562435938948;17655.823441765417;17687.646247074987;17701.291948321923;13330.400131994083;13380.665483362995;13437.924966002696;13451.932740336235;13465.730685386237;21854.45363865916;21972.450688732908;21973.121075156814;22069.668954965688;22175.44561385978;9040.593173307218;9112.453252804853;9141.222996045277;9188.310876684141;9189.678361257458;841.3941961787503;853.1979938857454;874.2306770042322;886.6054605700531;967.666155176463;26394.472110557683;29683.851580741957;30231.395372092877;31466.21334466611;31964.360712786078;1019.3476175247847;1046.2676126711333;1057.0697785948316;1062.4740753327153;1095.1622009162998;12540.623781286577;13354.5977672343;14755.852441475663;15198.240087995557;15535.84464155344;629146;674990;675714;676753;677999;58647.53381165505;58951.705241473515;58996.70501647464;59096;59129.81740365169;1342.9194248345132;1410.372384289002;1463.604826696786;1472.0431719382486;1475.845036271176;29156.27109322284;29223.561646574894;29455.410891781616;29828.403431931674;29854.10437686873;52930.2060469101;53577.732111339195;53767.731161343945;54004.18993715176;54063.72968135134;586375;594984;605526;606056;606369;22859.428514287276;27079.864600676872;27263.182104536896;27395.589066163626;27612.585811212448;44227.557724422346;44917;45379;45659.77170114128;46121.76939115417;37817.86546403612;38065.42901856419;38177.809110954266;38469.75000620551;38786.8363949082;32800;36984.815075924445;37148;37728;37745.81127094347;35513.46729799107;36250.274994499436;36690.715824946;36706.4494032595;37205;26257.737422626295;33395.499067514495;34319;34424.13939651529;35154.82422587871;20908.37274881756;24636.13773517918;25210.873945630887;25333.87333063323;25680.486390272465 17:01:05 INFO - PROCESS | 1955 | |2;jquery.html;373.86727711662354;392.43293440977783;392.9115948911493;397.72391659611344;414.71591959507737;19846.801531984675;20944.89527552364;21135.365939021834;21217.469563260925;21299.25452609159;20140.89929550354;20193.19227230909;20737.481562960933;20785.376438706862;20805;5756.222909907151;6027.156198132265;6061.45446909778;6062.878742425149;6067;5767.279090113736;5862.149988251701;5894.911576326359;5945.791897283596;5945.88108237835;5727.513161381266;5886.058230683092;5897.7346019429015;5922.1116832475045;5925.259342582177;5614.382417934024;5802.274715660543;5835.533157347402;5919.141724449953;5978.551608629326;5749.856253593652;5821.18503409523;5831.212786273841;5831.708414579254;5847.941520584783;19018.334358297532;22094.779052209433;24547;24834.751652483606;25285.49429011428;2069.699893515447;2106;2167.2089687264033;2204.437868343562;2359.1978727232654;9380.015098414704;9788.706338809847;9852.014798520162;12084.516619335216;15314.770278445832;1636.2064398766588;1744.127936031984;1766.5848525596475;1801.5946412057313;1805.142557285292;99013;123300;127289;130142.3492882539;130315;9611;11712.063034957184;11914.40427978615;11945.820812687816;12110.939445302893;14897.70204595902;14909.70180596382;14943;14949.925250373679;15011.849881501044;7292.708291668274;8555.230029297392;8669;8757.956210218908;8814.250788682906;8564.357673174536;8640.611172497252;8671.826563468696;8799;8807.867881981776;4573.336866154389;4646.186917289461;4679.555442232982;4714.222153344728;4715.292706094045;1939.1674867250313;1954.5113721569608;1978.3867001229664;2004.418718571598;2038.2051000109968;884.8672699095188;919.5012130227819;925.9629614815467;960.5101398286924;1032.396048311746;15150.696986060217;19078.236870525023;19997.200111995648;20321.695174572393;20375.184992600425;876.4741155306892;932.6549176804625;950.0357137505438;978.2125389061731;982.5922242269378;9913.405195688281;9979.950100249453;10502.601726509773;10505.9474702626;10515.94742026285;616365;618971.9051404714;620182;623235;629041;98420.50789746149;121584.78415215912;124404.133937991;132013.33993329972;141599;1018.1396719771781;1041.1046500009986;1049.2550289294534;1051.405955635079;1053.489057806963;15276.694466110393;15773.605659858136;17671.82328176753;17796.021218833088;17827.376041838994;32944.67055329416;53008.73495632497;53798.731006344715;54029.729851350494;54540;77287.43056840231;77937;78162.21837781549;78389.60805195938;80204.79692804496;6855.4172895304455;7376.594287314035;7384.446166537424;7784.532928024337;8311;7357.632118394166;10743.355398676105;12549.309787961749;12614.810777838524;12903.161294515827;10411.895881041395;10971.286866353661;11083.889161108607;11101.111911046935;11438.627364716287;8715.477071375737;10490.213234007633;10884.40135792539;10998.285111467729;11133.665990020312;10789.676309710721;10926;11285.887141128813;11353.432328383713;11692.824607631062;7445.4043676506835;7527.924720752722;7561;7561.6597253122645;7562.735304264324;10201.795964080826;10891.01980821698;11156.721081973015;11232.382218978035;11243.156763242627 17:01:05 INFO - PROCESS | 1955 | |3;mootools.html;1383.3221721356538;1428.6071330384143;1507.9095254284744;1922.7019811929147;1926.5376309685662;6785.76249831256;8653.264472519844;8797.384183107186;9305.4881981491;9319.58061887215;7461.626918654059;8787.253083487894;8933.419327743692;8938.37431379802;8988.235999940012;2241.7197850268717;2297.9659153380962;2410.132352353156;2449.008151698565;2456.0053178462663;1874.306506592556;2389.4145934246135;2400.459896523277;2419.2379400488876;2450.901963921441;2127.850960481338;2344.4138965258685;2428.86641234732;2450.669159663449;2453.521563295158;2098.5834561670813;2373.193114341124;2406.482606239649;2414.9857060034738;2426.46617744096;1967.8819270843794;2400.567897778399;2420.322309753272;2426.842255253403;2440.414300567869;8830.779230519223;8839.116088391174;8839.292856571523;8965.641374345018;9045.321600879895;5304.522592966613;5377.139657654757;5412.3505179378335;5487.807926722586;5529.281193444866;3397.524346591479;3619.1133172372674;3650.0692323457347;3650.7809531427934;3696.057505336122;1000.7498125468633;1046.712154157605;1049.0610903241632;1068.5191663751343;1072.651388298806;11871.821922671166;12046;12057.698557536043;12058.638240852788;12071.698207544794;4847.788052986753;4913.852584422473;4957.082939656175;4989.151844186497;5011.724355160428;10153.340032897839;10154.796904061877;10156.23828212887;10165.796684066278;10190.388576685422;5413.891722165614;5489;5502.312210973629;5532.972335138298;5557.805476808292;4488.753118578509;4821.879453013633;4898.779554920037;5046.394432668103;5082.567981721595;4094.1749302297703;4094.3244364679495;4139.8965025874595;4152.8338866445;4156.501219853637;1344.2203521957242;1363.6863521389937;1371.163590209991;1374.1686279800776;1375.6835927736677;916.2120576304372;922.3497434308796;943.5612440215333;945.0785484152896;967.811276801017;5408.756605952742;5762.135679648002;5905.763769449174;6123.785667501616;6127.927612667765;372.27035011378;385.7897445891996;448.96408287336936;452.3079688077247;461.82681494439584;3986.661133803659;4053.3717273822604;4071.653909417733;4085.0400155963325;4122.855700050543;10466.21503387249;10672.839907401396;10864.674059778219;10902.400367979679;11012.724681883017;10477.266591338686;10780.029797318122;10833.891661083446;10888.020078193;10992.725181870357;8174.713885013996;8227.42408031432;8275.668973241003;8293.087760346358;8374.204450577185;5764.711764411846;5930.7924222652;5969.7313620888035;5983.072623743326;6031.36670649593;9545.188658964065;9735.367201131903;10167.084962353423;10232.02795734404;10264;8048.436609437284;9902.653407130716;10018.39889606626;10223.284370094021;10402.323848949794;6935.479839011993;7376.7049318026675;7464.178940316452;7520.323170914644;7657.88513172314;7755.379569634529;8222.054463736507;8591.828163436821;8603;8711.91288087111;7573.772786816405;7723.111842138226;7782;7823.882641760492;7840.568768717775;7136.785896423115;7180.892286615601;7184.928150718426;7329.853402931805;7351.338379545867;7087.3267039631155;7136.500444968802;7188.676509556978;7202.819929501595;7236.20401755817;4885.609151267961;5041.798328066836;5099.158638824552;5147;5149.995750828552;4273.423087883096;4975.7063163577;5006.483790523691;5062.240663900445;5153.561947234377 17:01:05 INFO - PROCESS | 1955 | |4;prototype.html;301.16426915310024;313.9764517661173;314.7544914966326;317.1010186122343;323.3436124666927;4937.358143441356;5359.839204823861;5495.477929596692;5645.661260324383;5694.373618901923;4974.179260422026;5523.7238138093135;5562.749676264578;5580.391706335137;5582.441755824416;3544.379733546632;4002.939955900664;4104.692148088898;4238.597333253336;4249.830006799724;3973.8013099344917;4128.318827393491;4255.382969469425;4260.403543503912;4267.957320426803;4120.072983578686;4168.937465938014;4171.91656166878;4177.16456708658;4195.5175154857125;4188.827128404052;4191.81136848841;4199.496060472732;4218.388333691628;4226.704130710851;3665;3670.1832592866435;3670.9632903671154;3678.3746763050076;3680.576733675635;4218.767967761772;4962.925556116661;5273.525382715574;5294.326365185557;5336.546393556552;4398.96471434709;4506.301523263866;4765.976170119162;4834.661573689843;4876.609871210295;4602.907941841145;4792.568668819823;5700.914486282709;5827.776167004934;5834.824955251349;3044.634643842753;3283.819389933553;3923.313420151462;3924.2543916655745;3930.823112959895;5860.941390586082;6054.606450580698;6071.6642338685415;6097.146399504073;6142.938570614281;3967.9603203968168;3971.72197946141;3979.7413168143976;3999.580044095328;4006.357393468722;3619.3847046002243;3777.8488860445273;4041.7170798044444;4042.3936409538214;4054.412110243998;4685.344051832746;4711.434627844612;4711.552402521755;4738.668293219442;4742.359781429532;5001.699898006132;5442.619016668875;5473.562115030788;5521.046240261441;5850.385709500526;1145;1341.6042267531102;1359.7688392973218;1370.9314534273246;1373.4094339433939;806.4435539477741;818.378032695156;826.6938237584602;841.7685136587487;846.6232526525637;4109.91780164395;4111.383292506148;4417.955820441819;4587.857317737361;4636.86089417318;4266.530681625019;4317.676174286941;4467.329900514948;4923.070772890124;5162.277281180638;3884.3008258513173;4309.870703878889;4604.631629469634;4624.745638989888;4652.720836749806;2379.904803807828;2527.797776177901;2564.730703276129;2623.7507436793476;2663.721413721386;5227.03986005248;5271.2868317796865;5299.5230429261555;5338.466153384708;5365.444021233877;4555.76994211561;4604.641630718946;4630.333080091164;4724.527547245248;5266.3943646480375;4762.8333008345235;5189.40321862983;5222.660527065728;5300.535251108176;5435.83692489226;4420.756858372821;4626.537346265347;5018.895842914553;5122.359705036871;5131.640115369355;4153.792310384529;4779.211430113992;4976.30331753562;4993.675411098267;4994.675346102492;4584.518625544403;4763.642361926834;5060.318031443341;5090.650977490894;5332.320319099471;2853.915512105387;3061.4948533492616;3475.7740746851373;3488.9127771804892;3497.370473314828;4568.132054909556;4876.658633895593;5029.59763218949;5053.191489361684;5069.898602028013;5389.649672771257;5877.942720047949;5885.822835432845;5890.764369425175;5899.498542623754;5225.145073498853;5812.808374283346;5849.473547380756;5872.500837428866;5886.793962211302;4744.359511465908;4995.350604421402;5112.79548818058;5576.386597474194;5727.479908840097;5167.6641018333685;5459.972700136633;5474.534664553557;5867.706614669335;5917.260342457193;4913.828016019565;5522.972385138049;5699.544036476989;5708.914366284592;5734.311882574118 17:01:05 INFO - PROCESS | 1955 | |5;yui.html;644.7388807532949;679.1849780263685;705.1960764728213;722.6639612580149;735.9227281135483;12953.417096230669;13642.045056846026;14770.409183632655;15537.37850485982;15578.376864925403;13456.058075934665;16070.116143612126;16455.177241137924;16486.83513164868;16514.33942642293;723.1683563901503;920.3695468604011;932.883389576303;933.3326671429926;934.5047125023714;743.672783975052;930.7347405989294;932.9207017403529;936.7096200177932;936.7377134402378;975.9707208783748;979.2508730820928;986.1519093579516;986.5264672956985;988.6243227573513;969.2197780786464;974.1524873360187;975.9560819763128;976.9609215631366;978.4765150644407;706.8232941764559;712.6116266634697;725.3435640745081;725.941924646027;729.3326606155362;15131.243437828174;15223.695526089416;15356.616084597863;15388.538343849703;15420.614484637974;12193.329366884818;12228.205166664138;12300.200486968319;12377.009839212842;12464.002879769596;9840.36037657557;10038.749031274276;10195.4392508412;10549.683509494727;10594.41730704811;3414.419548676706;3462.3248466549035;3470.670286322795;3512.666296701809;3566.982165089184;75246.62376688136;75544.24455755427;75901;76170;76232.23767762362;9092.36353455265;9143.862842057375;9146.81706365869;9248.306377021616;9471.952640236756;17019.063951482538;18068.548286292684;20596.485087872923;20637.071331790106;20672.483187920123;7377.077865266841;7391.223921488274;7402.407807375397;7447.776566703008;7507.849843003219;4081.6530594899173;4119.896630573388;4146.37804329347;4168.145530166309;4168.8332466700995;3493.5807703075284;3546.7517273790586;3571.8749843755345;3599.6760291573883;3600.7299452541165;630.5145038320468;644.5906849150823;644.6615526848349;649.1463725201345;665.3047565294279;517.510952150222;548.0600769680018;590.3948452835878;608.2184393054991;608.7534548507862;5489.560835133179;6347.968260158762;7377.299156580224;8349.206825351583;8909.688160914337;467.6959976015549;579.3308728418683;580.6487075319371;603.2429301226932;610.2768219659629;3763.9435408468894;3833.099221682902;3859.671927886105;3955.7626542407547;4002.1384709932536;81068;81343.59328203439;83087;83593.58203208946;83647.16352836393;68064.3193568068;68078.29804254933;70027;70819.2918070823;73044;47619;63385;64525.67737161378;67479;67755;8263.917360826315;8297.170282971654;8403.915960840557;8437.451565648213;8478.788030299293;41424.75725728233;42179.367309491;42199.367009494264;42784.358234625885;43023.70928872139;53745.73127134339;56587.71706141443;57332.71333643305;58273.4172658268;58803.41196587979;10521.736956576147;10629.9468502657;10668.786624267626;10802.513886874953;11060.281081729661;11774.52901883915;11775.23460975034;11776.234544754563;11794.705132371759;12001.459934302802;9399.825021872266;10870.347779133277;10971.067459266053;11039.558417663204;11188.83216751733;10687.412192329497;11075.833862491907;11244.887551124384;11338.829917551078;11367.943160284476;10782.137429005817;10854.240203185702;10928.562857485611;11052.171087168334;11115.44422778874;8826.779330516789;9389.812203756022;9394.906050939404;9497.097775711296;9584.185344245818;8381.245687888118;9460.668876589287;9896.703098907044;9931.404115753077;10016.649417270652 17:01:05 INFO - PROCESS | 1955 | __end_tp_report 17:01:05 INFO - PROCESS | 1955 | __start_cc_report 17:01:05 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,414 17:01:05 INFO - PROCESS | 1955 | __end_cc_report 17:01:05 INFO - PROCESS | 1955 | __startTimestamp1463011265021__endTimestamp 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | ------- Summary: start ------- 17:01:05 INFO - PROCESS | 1955 | Number of tests: 6 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5797.44 Median:6067.73 stddev:793.53 (13.1%) stddev-sans-first:774.92 17:01:05 INFO - PROCESS | 1955 | Values: 3483.3 3516.1 3518.1 3524.8 3565.5 4691.0 5879.8 5944.9 5969.2 5969.8 5751.9 5889.3 5902.9 5970.5 6019.8 4668.2 5254.9 5302.9 5361.9 5368.8 262.6 5304.0 5321.2 5351.9 5363.2 5309.9 5317.3 5323.4 5324.3 5336.3 5325.7 5339.5 5351.1 5386.5 5398.5 4990.3 5220.5 5260.2 5261.7 5338.6 5630.5 5749.3 5792.6 5824.3 5845.3 5127.9 5975.0 6035.7 6241.3 6315.5 5702.5 6273.8 6275.3 6348.6 6358.4 4559.4 5473.6 5553.5 6053.2 6056.2 6222.0 6307.0 6419.1 6467.1 6491.5 6142.6 6171.8 6185.0 6191.0 6202.5 5276.7 6273.3 6293.7 6336.5 6345.6 6152.4 6176.2 6218.8 6245.4 6252.2 6276.1 6294.0 6312.6 6315.1 6358.8 5037.8 5092.4 5098.5 5100.4 5108.9 4349.2 4371.2 4373.3 4424.6 4432.8 5946.9 5974.1 6011.7 6077.8 6079.3 6360.3 6365.7 6373.5 6397.6 6404.0 5840.2 5962.8 5976.1 6008.5 6025.4 5551.1 5573.9 5579.0 5587.2 5588.5 6200.7 6587.4 6591.3 6593.0 6618.0 6253.2 6292.5 6319.5 6338.9 6356.0 6505.9 6536.9 6543.3 6546.4 6570.6 5492.0 6067.7 6208.8 6239.0 6324.2 5577.9 6197.2 6258.7 6365.5 6419.3 6271.3 6325.2 6335.5 6340.0 6346.8 6132.7 6135.2 6161.4 6316.4 6322.7 6012.3 6153.0 6171.3 6180.6 6205.1 6314.3 6316.6 6328.3 6342.5 6348.0 6051.7 6118.3 6149.8 6164.3 6197.9 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:71269.77 Median:39241.11 stddev:158282.67 (403.4%) stddev-sans-first:158637.50 17:01:05 INFO - PROCESS | 1955 | Values: 498.2 503.7 515.8 531.5 536.7 34083.5 34403.1 39216.4 41969.4 41984.6 34116.5 42804.6 42825.1 42850.6 43041.0 8319.9 9521.0 9792.9 9801.2 9854.1 9480.8 9526.0 9633.3 9723.5 9774.2 8515.9 9688.3 9813.0 9823.0 9835.7 7848.4 9791.0 9806.2 9811.1 9811.9 9571.0 9640.2 9649.7 9662.5 9666.3 39281.8 39662.4 39685.6 39741.8 39889.0 2787.3 3061.2 3121.9 3205.7 3464.2 29301.9 29536.7 29822.4 30598.1 30748.8 2714.2 2780.2 2781.0 2788.6 2828.1 483633.0 491503.0 497463.0 498143.0 499646.0 69782.0 70199.3 70335.6 70507.0 70512.6 38302.0 38988.8 39265.8 40420.0 40567.8 17401.0 17502.6 17655.8 17687.6 17701.3 13330.4 13380.7 13437.9 13451.9 13465.7 21854.5 21972.5 21973.1 22069.7 22175.4 9040.6 9112.5 9141.2 9188.3 9189.7 841.4 853.2 874.2 886.6 967.7 26394.5 29683.9 30231.4 31466.2 31964.4 1019.3 1046.3 1057.1 1062.5 1095.2 12540.6 13354.6 14755.9 15198.2 15535.8 629146.0 674990.0 675714.0 676753.0 677999.0 58647.5 58951.7 58996.7 59096.0 59129.8 1342.9 1410.4 1463.6 1472.0 1475.8 29156.3 29223.6 29455.4 29828.4 29854.1 52930.2 53577.7 53767.7 54004.2 54063.7 586375.0 594984.0 605526.0 606056.0 606369.0 22859.4 27079.9 27263.2 27395.6 27612.6 44227.6 44917.0 45379.0 45659.8 46121.8 37817.9 38065.4 38177.8 38469.8 38786.8 32800.0 36984.8 37148.0 37728.0 37745.8 35513.5 36250.3 36690.7 36706.4 37205.0 26257.7 33395.5 34319.0 34424.1 35154.8 20908.4 24636.1 25210.9 25333.9 25680.5 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:35387.80 Median:29115.08 stddev:103592.83 (355.8%) stddev-sans-first:103850.07 17:01:05 INFO - PROCESS | 1955 | Values: 373.9 392.4 392.9 397.7 414.7 19846.8 20944.9 21135.4 21217.5 21299.3 20140.9 20193.2 20737.5 20785.4 20805.0 5756.2 6027.2 6061.5 6062.9 6067.0 5767.3 5862.1 5894.9 5945.8 5945.9 5727.5 5886.1 5897.7 5922.1 5925.3 5614.4 5802.3 5835.5 5919.1 5978.6 5749.9 5821.2 5831.2 5831.7 5847.9 19018.3 22094.8 24547.0 24834.8 25285.5 2069.7 2106.0 2167.2 2204.4 2359.2 9380.0 9788.7 9852.0 12084.5 15314.8 1636.2 1744.1 1766.6 1801.6 1805.1 99013.0 123300.0 127289.0 130142.3 130315.0 9611.0 11712.1 11914.4 11945.8 12110.9 14897.7 14909.7 14943.0 14949.9 15011.8 7292.7 8555.2 8669.0 8758.0 8814.3 8564.4 8640.6 8671.8 8799.0 8807.9 4573.3 4646.2 4679.6 4714.2 4715.3 1939.2 1954.5 1978.4 2004.4 2038.2 884.9 919.5 926.0 960.5 1032.4 15150.7 19078.2 19997.2 20321.7 20375.2 876.5 932.7 950.0 978.2 982.6 9913.4 9980.0 10502.6 10505.9 10515.9 616365.0 618971.9 620182.0 623235.0 629041.0 98420.5 121584.8 124404.1 132013.3 141599.0 1018.1 1041.1 1049.3 1051.4 1053.5 15276.7 15773.6 17671.8 17796.0 17827.4 32944.7 53008.7 53798.7 54029.7 54540.0 77287.4 77937.0 78162.2 78389.6 80204.8 6855.4 7376.6 7384.4 7784.5 8311.0 7357.6 10743.4 12549.3 12614.8 12903.2 10411.9 10971.3 11083.9 11101.1 11438.6 8715.5 10490.2 10884.4 10998.3 11133.7 10789.7 10926.0 11285.9 11353.4 11692.8 7445.4 7527.9 7561.0 7561.7 7562.7 10201.8 10891.0 11156.7 11232.4 11243.2 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5754.83 Median:4892.19 stddev:3245.78 (66.3%) stddev-sans-first:3238.26 17:01:05 INFO - PROCESS | 1955 | Values: 1383.3 1428.6 1507.9 1922.7 1926.5 6785.8 8653.3 8797.4 9305.5 9319.6 7461.6 8787.3 8933.4 8938.4 8988.2 2241.7 2298.0 2410.1 2449.0 2456.0 1874.3 2389.4 2400.5 2419.2 2450.9 2127.9 2344.4 2428.9 2450.7 2453.5 2098.6 2373.2 2406.5 2415.0 2426.5 1967.9 2400.6 2420.3 2426.8 2440.4 8830.8 8839.1 8839.3 8965.6 9045.3 5304.5 5377.1 5412.4 5487.8 5529.3 3397.5 3619.1 3650.1 3650.8 3696.1 1000.7 1046.7 1049.1 1068.5 1072.7 11871.8 12046.0 12057.7 12058.6 12071.7 4847.8 4913.9 4957.1 4989.2 5011.7 10153.3 10154.8 10156.2 10165.8 10190.4 5413.9 5489.0 5502.3 5533.0 5557.8 4488.8 4821.9 4898.8 5046.4 5082.6 4094.2 4094.3 4139.9 4152.8 4156.5 1344.2 1363.7 1371.2 1374.2 1375.7 916.2 922.3 943.6 945.1 967.8 5408.8 5762.1 5905.8 6123.8 6127.9 372.3 385.8 449.0 452.3 461.8 3986.7 4053.4 4071.7 4085.0 4122.9 10466.2 10672.8 10864.7 10902.4 11012.7 10477.3 10780.0 10833.9 10888.0 10992.7 8174.7 8227.4 8275.7 8293.1 8374.2 5764.7 5930.8 5969.7 5983.1 6031.4 9545.2 9735.4 10167.1 10232.0 10264.0 8048.4 9902.7 10018.4 10223.3 10402.3 6935.5 7376.7 7464.2 7520.3 7657.9 7755.4 8222.1 8591.8 8603.0 8711.9 7573.8 7723.1 7782.0 7823.9 7840.6 7136.8 7180.9 7184.9 7329.9 7351.3 7087.3 7136.5 7188.7 7202.8 7236.2 4885.6 5041.8 5099.2 5147.0 5150.0 4273.4 4975.7 5006.5 5062.2 5153.6 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:4394.88 Median:4743.36 stddev:1343.51 (28.3%) stddev-sans-first:1311.67 17:01:05 INFO - PROCESS | 1955 | Values: 301.2 314.0 314.8 317.1 323.3 4937.4 5359.8 5495.5 5645.7 5694.4 4974.2 5523.7 5562.7 5580.4 5582.4 3544.4 4002.9 4104.7 4238.6 4249.8 3973.8 4128.3 4255.4 4260.4 4268.0 4120.1 4168.9 4171.9 4177.2 4195.5 4188.8 4191.8 4199.5 4218.4 4226.7 3665.0 3670.2 3671.0 3678.4 3680.6 4218.8 4962.9 5273.5 5294.3 5336.5 4399.0 4506.3 4766.0 4834.7 4876.6 4602.9 4792.6 5700.9 5827.8 5834.8 3044.6 3283.8 3923.3 3924.3 3930.8 5860.9 6054.6 6071.7 6097.1 6142.9 3968.0 3971.7 3979.7 3999.6 4006.4 3619.4 3777.8 4041.7 4042.4 4054.4 4685.3 4711.4 4711.6 4738.7 4742.4 5001.7 5442.6 5473.6 5521.0 5850.4 1145.0 1341.6 1359.8 1370.9 1373.4 806.4 818.4 826.7 841.8 846.6 4109.9 4111.4 4418.0 4587.9 4636.9 4266.5 4317.7 4467.3 4923.1 5162.3 3884.3 4309.9 4604.6 4624.7 4652.7 2379.9 2527.8 2564.7 2623.8 2663.7 5227.0 5271.3 5299.5 5338.5 5365.4 4555.8 4604.6 4630.3 4724.5 5266.4 4762.8 5189.4 5222.7 5300.5 5435.8 4420.8 4626.5 5018.9 5122.4 5131.6 4153.8 4779.2 4976.3 4993.7 4994.7 4584.5 4763.6 5060.3 5090.7 5332.3 2853.9 3061.5 3475.8 3488.9 3497.4 4568.1 4876.7 5029.6 5053.2 5069.9 5389.6 5877.9 5885.8 5890.8 5899.5 5225.1 5812.8 5849.5 5872.5 5886.8 4744.4 4995.4 5112.8 5576.4 5727.5 5167.7 5460.0 5474.5 5867.7 5917.3 4913.8 5523.0 5699.5 5708.9 5734.3 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:16830.68 Median:29696.90 stddev:22842.14 (76.9%) stddev-sans-first:22873.88 17:01:05 INFO - PROCESS | 1955 | Values: 644.7 679.2 705.2 722.7 735.9 12953.4 13642.0 14770.4 15537.4 15578.4 13456.1 16070.1 16455.2 16486.8 16514.3 723.2 920.4 932.9 933.3 934.5 743.7 930.7 932.9 936.7 936.7 976.0 979.3 986.2 986.5 988.6 969.2 974.2 976.0 977.0 978.5 706.8 712.6 725.3 725.9 729.3 15131.2 15223.7 15356.6 15388.5 15420.6 12193.3 12228.2 12300.2 12377.0 12464.0 9840.4 10038.7 10195.4 10549.7 10594.4 3414.4 3462.3 3470.7 3512.7 3567.0 75246.6 75544.2 75901.0 76170.0 76232.2 9092.4 9143.9 9146.8 9248.3 9472.0 17019.1 18068.5 20596.5 20637.1 20672.5 7377.1 7391.2 7402.4 7447.8 7507.8 4081.7 4119.9 4146.4 4168.1 4168.8 3493.6 3546.8 3571.9 3599.7 3600.7 630.5 644.6 644.7 649.1 665.3 517.5 548.1 590.4 608.2 608.8 5489.6 6348.0 7377.3 8349.2 8909.7 467.7 579.3 580.6 603.2 610.3 3763.9 3833.1 3859.7 3955.8 4002.1 81068.0 81343.6 83087.0 83593.6 83647.2 68064.3 68078.3 70027.0 70819.3 73044.0 47619.0 63385.0 64525.7 67479.0 67755.0 8263.9 8297.2 8403.9 8437.5 8478.8 41424.8 42179.4 42199.4 42784.4 43023.7 53745.7 56587.7 57332.7 58273.4 58803.4 10521.7 10629.9 10668.8 10802.5 11060.3 11774.5 11775.2 11776.2 11794.7 12001.5 9399.8 10870.3 10971.1 11039.6 11188.8 10687.4 11075.8 11244.9 11338.8 11367.9 10782.1 10854.2 10928.6 11052.2 11115.4 8826.8 9389.8 9394.9 9497.1 9584.2 8381.2 9460.7 9896.7 9931.4 10016.6 17:01:05 INFO - PROCESS | 1955 | -------- Summary: end -------- 17:01:05 INFO - PROCESS | 1955 | 17:01:05 INFO - TEST-INFO | 1955: exit 0 17:01:05 INFO - TEST-OK | dromaeo_css | took 1112735ms 17:01:05 INFO - TEST-START | kraken 17:01:05 INFO - operating with platform_type : mac_ 17:01:05 INFO - Initialising browser for kraken test... 17:01:05 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpaPRzCL/profile http://localhost:51999/getInfo.html) 17:01:08 INFO - PROCESS | 1963 | __metrics Screen width/height:1600/1200 17:01:08 INFO - PROCESS | 1963 | colorDepth:24 17:01:08 INFO - PROCESS | 1963 | Browser inner width/height: 1024/689 17:01:08 INFO - PROCESS | 1963 | __metrics 17:01:08 ERROR - PROCESS | 1963 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 17:01:09 INFO - TEST-INFO | 1963: exit 0 17:01:09 INFO - Browser initialized. 17:01:09 INFO - Running cycle 1/1 for kraken test... 17:01:09 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpaPRzCL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:01:21 INFO - PROCESS | 1966 | RSS: Main: 250245120 17:01:21 INFO - PROCESS | 1966 | 17:01:24 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51999/tests/kraken/driver.html?testName=audio-beat-detection) 17:01:25 INFO - PROCESS | 1966 | RSS: Main: 262156288 17:01:25 INFO - PROCESS | 1966 | 17:01:27 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51999/tests/kraken/driver.html?testName=audio-dft) 17:01:28 INFO - PROCESS | 1966 | RSS: Main: 266932224 17:01:28 INFO - PROCESS | 1966 | 17:01:32 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51999/tests/kraken/driver.html?testName=audio-fft) 17:01:32 INFO - PROCESS | 1966 | RSS: Main: 267325440 17:01:32 INFO - PROCESS | 1966 | 17:01:34 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51999/tests/kraken/driver.html?testName=audio-oscillator) 17:01:35 INFO - PROCESS | 1966 | RSS: Main: 267902976 17:01:35 INFO - PROCESS | 1966 | 17:01:37 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51999/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:01:38 INFO - PROCESS | 1966 | RSS: Main: 268398592 17:01:38 INFO - PROCESS | 1966 | 17:01:41 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51999/tests/kraken/driver.html?testName=imaging-darkroom) 17:01:42 INFO - PROCESS | 1966 | RSS: Main: 269156352 17:01:42 INFO - PROCESS | 1966 | 17:01:45 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51999/tests/kraken/driver.html?testName=imaging-desaturate) 17:01:46 INFO - PROCESS | 1966 | RSS: Main: 270602240 17:01:46 INFO - PROCESS | 1966 | 17:01:48 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51999/tests/kraken/driver.html?testName=json-parse-financial) 17:01:49 INFO - PROCESS | 1966 | RSS: Main: 271339520 17:01:49 INFO - PROCESS | 1966 | 17:01:51 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51999/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:01:52 INFO - PROCESS | 1966 | RSS: Main: 270372864 17:01:52 INFO - PROCESS | 1966 | 17:01:54 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:01:54 INFO - PROCESS | 1966 | RSS: Main: 270888960 17:01:54 INFO - PROCESS | 1966 | 17:01:57 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:01:57 INFO - PROCESS | 1966 | RSS: Main: 270573568 17:01:57 INFO - PROCESS | 1966 | 17:02:00 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:02:01 INFO - PROCESS | 1966 | RSS: Main: 271155200 17:02:01 INFO - PROCESS | 1966 | 17:02:04 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:02:05 INFO - PROCESS | 1966 | RSS: Main: 271142912 17:02:05 INFO - PROCESS | 1966 | 17:02:06 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:51999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51999/tests/kraken/driver.html?testName=ai-astar) 17:02:07 INFO - PROCESS | 1966 | RSS: Main: 273281024 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | __start_tp_report 17:02:07 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 17:02:07 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 17:02:07 INFO - PROCESS | 1966 | |i|pagename|runs| 17:02:07 INFO - PROCESS | 1966 | |0;ai-astar;116;117;118;174;102;160;156;155;119;115 17:02:07 INFO - PROCESS | 1966 | |1;audio-beat-detection;154;125;151;242;120;144;136;130;168;120 17:02:07 INFO - PROCESS | 1966 | |2;audio-dft;283;275;226;203;199;201;198;197;243;199 17:02:07 INFO - PROCESS | 1966 | |3;audio-fft;77;81;78;177;57;58;64;58;140;50 17:02:07 INFO - PROCESS | 1966 | |4;audio-oscillator;75;95;165;112;114;96;128;99;103;125 17:02:07 INFO - PROCESS | 1966 | |5;imaging-gaussian-blur;92;89;105;128;92;89;91;122;108;89 17:02:07 INFO - PROCESS | 1966 | |6;imaging-darkroom;97;96;97;150;136;97;123;97;97;97 17:02:07 INFO - PROCESS | 1966 | |7;imaging-desaturate;89;93;90;91;90;90;91;90;90;93 17:02:07 INFO - PROCESS | 1966 | |8;json-parse-financial;60;61;60;62;63;68;75;176;100;61 17:02:07 INFO - PROCESS | 1966 | |9;json-stringify-tinderbox;66;66;78;108;68;68;85;105;102;104 17:02:07 INFO - PROCESS | 1966 | |10;stanford-crypto-aes;91;113;109;107;96;86;86;85;91;88 17:02:07 INFO - PROCESS | 1966 | |11;stanford-crypto-ccm;138;143;138;139;138;145;143;138;219;149 17:02:07 INFO - PROCESS | 1966 | |12;stanford-crypto-pbkdf2;164;187;234;172;170;169;173;173;166;174 17:02:07 INFO - PROCESS | 1966 | |13;stanford-crypto-sha256-iterative;66;68;66;66;62;61;74;96;86;85 17:02:07 INFO - PROCESS | 1966 | __end_tp_report 17:02:07 INFO - PROCESS | 1966 | __start_cc_report 17:02:07 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,1086 17:02:07 INFO - PROCESS | 1966 | __end_cc_report 17:02:07 INFO - PROCESS | 1966 | __startTimestamp1463011327171__endTimestamp 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | ------- Summary: start ------- 17:02:07 INFO - PROCESS | 1966 | Number of tests: 14 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#0] ai-astar Cycles:10 Average:133.20 Median:137.00 stddev:25.11 (18.3%) stddev-sans-first:25.85 17:02:07 INFO - PROCESS | 1966 | Values: 116.0 117.0 118.0 174.0 102.0 160.0 156.0 155.0 119.0 115.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#1] audio-beat-detection Cycles:10 Average:149.00 Median:147.50 stddev:36.29 (24.6%) stddev-sans-first:38.45 17:02:07 INFO - PROCESS | 1966 | Values: 154.0 125.0 151.0 242.0 120.0 144.0 136.0 130.0 168.0 120.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#2] audio-dft Cycles:10 Average:222.40 Median:214.50 stddev:33.41 (15.6%) stddev-sans-first:27.31 17:02:07 INFO - PROCESS | 1966 | Values: 283.0 275.0 226.0 203.0 199.0 201.0 198.0 197.0 243.0 199.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#3] audio-fft Cycles:10 Average:84.00 Median:61.00 stddev:41.52 (68.1%) stddev-sans-first:43.96 17:02:07 INFO - PROCESS | 1966 | Values: 77.0 81.0 78.0 177.0 57.0 58.0 64.0 58.0 140.0 50.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#4] audio-oscillator Cycles:10 Average:111.20 Median:120.00 stddev:24.48 (20.4%) stddev-sans-first:22.19 17:02:07 INFO - PROCESS | 1966 | Values: 75.0 95.0 165.0 112.0 114.0 96.0 128.0 99.0 103.0 125.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#5] imaging-gaussian-blur Cycles:10 Average:100.50 Median:89.00 stddev:14.63 (16.4%) stddev-sans-first:15.19 17:02:07 INFO - PROCESS | 1966 | Values: 92.0 89.0 105.0 128.0 92.0 89.0 91.0 122.0 108.0 89.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:97.00 stddev:20.11 (20.7%) stddev-sans-first:20.87 17:02:07 INFO - PROCESS | 1966 | Values: 97.0 96.0 97.0 150.0 136.0 97.0 123.0 97.0 97.0 97.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:90.50 stddev:1.34 (1.5%) stddev-sans-first:1.27 17:02:07 INFO - PROCESS | 1966 | Values: 89.0 93.0 90.0 91.0 90.0 90.0 91.0 90.0 90.0 93.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#8] json-parse-financial Cycles:10 Average:78.60 Median:61.50 stddev:36.36 (59.1%) stddev-sans-first:37.94 17:02:07 INFO - PROCESS | 1966 | Values: 60.0 61.0 60.0 62.0 63.0 68.0 75.0 176.0 100.0 61.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#9] json-stringify-tinderbox Cycles:10 Average:85.00 Median:67.00 stddev:18.04 (26.9%) stddev-sans-first:17.77 17:02:07 INFO - PROCESS | 1966 | Values: 66.0 66.0 78.0 108.0 68.0 68.0 85.0 105.0 102.0 104.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:87.00 stddev:10.58 (12.2%) stddev-sans-first:11.11 17:02:07 INFO - PROCESS | 1966 | Values: 91.0 113.0 109.0 107.0 96.0 86.0 86.0 85.0 91.0 88.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:143.00 stddev:24.88 (17.4%) stddev-sans-first:26.07 17:02:07 INFO - PROCESS | 1966 | Values: 138.0 143.0 138.0 139.0 138.0 145.0 143.0 138.0 219.0 149.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:173.00 stddev:20.56 (11.9%) stddev-sans-first:21.15 17:02:07 INFO - PROCESS | 1966 | Values: 164.0 187.0 234.0 172.0 170.0 169.0 173.0 173.0 166.0 174.0 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - PROCESS | 1966 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:71.00 stddev:11.93 (16.8%) stddev-sans-first:12.38 17:02:07 INFO - PROCESS | 1966 | Values: 66.0 68.0 66.0 66.0 62.0 61.0 74.0 96.0 86.0 85.0 17:02:07 INFO - PROCESS | 1966 | -------- Summary: end -------- 17:02:07 INFO - PROCESS | 1966 | 17:02:07 INFO - TEST-INFO | 1966: exit 0 17:02:07 INFO - TEST-OK | kraken | took 62416ms 17:02:07 INFO - SUITE-END | took 1175s 17:02:08 INFO - Completed test suite (00:19:35) 17:02:08 INFO - javascript benchmark 17:02:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3483.2859263850905, 3516.1209697575605, 3518.050126465854, 3524.806135662539, 3565.483004964282, 4691.0, 5879.823605291843, 5944.910826337608, 5969.194158788568, 5969.820905372835, 5751.856203594913, 5889.293284805819, 5902.911456328158, 5970.492508136814, 6019.849503762408, 4668.229742092551, 5254.947450525503, 5302.92045619316, 5361.919571206435, 5368.785248590071, 262.64637873252593, 5304.018756530035, 5321.175217841241, 5351.866203344928, 5363.168708850115, 5309.893802123975, 5317.255584218232, 5323.361196656407, 5324.281222035014, 5336.30628018359, 5325.680459172462, 5339.492748188916, 5351.143816989302, 5386.48828361305, 5398.541124004464, 4990.30135780991, 5220.504052115043, 5260.210968354739, 5261.684298942076, 5338.599605029638, 5630.521405680522, 5749.252597162384, 5792.594518383715, 5824.3302020267365, 5845.269341332333, 5127.871803204913, 5974.97012514939, 6035.702324000361, 6241.313455519889, 6315.494760419201, 5702.486776190163, 6273.843153921142, 6275.309715931244, 6348.619082854997, 6358.395952384516, 4559.384483094773, 5473.616846820724, 5553.527950124244, 6053.1828203192445, 6056.242969628796, 6222.0, 6306.9684651576445, 6419.069234960904, 6467.094606755058, 6491.51313651478, 6142.590862071504, 6171.7531298748445, 6185.0, 6190.969045154746, 6202.472789812826, 5276.683398996072, 6273.3099359071375, 6293.653849038347, 6336.52476064297, 6345.619262844244, 6152.415520525543, 6176.245819149088, 6218.751249949952, 6245.406686364788, 6252.187215661935, 6276.121343011982, 6293.96853015732, 6312.558120931582, 6315.115883776275, 6358.8410289742205, 5037.798488060436, 5092.439831618519, 5098.5411312982005, 5100.362454693163, 5108.872278193075, 4349.217140914666, 4371.213181627274, 4373.2565463871215, 4424.557544245549, 4432.844850430284, 5946.910796338058, 5974.074018527135, 6011.729472173763, 6077.817665470043, 6079.300880398722, 6360.265534358026, 6365.713542890581, 6373.52198585108, 6397.584157029872, 6404.0, 5840.211571437886, 5962.791302304398, 5976.073708575178, 6008.459238668541, 6025.367336429699, 5551.111822108524, 5573.944260557424, 5578.995780759421, 5587.217789509472, 5588.469095435927, 6200.689965501797, 6587.407133357925, 6591.307912669101, 6593.044008618627, 6618.0, 6253.2496100466415, 6292.465140463112, 6319.526035547445, 6338.90491642635, 6356.0465930109, 6505.902411463737, 6536.869262614626, 6543.345665433419, 6546.410823025951, 6570.5729127606555, 5492.038893193755, 6067.726952287246, 6208.81373558794, 6239.0, 6324.209473815773, 5577.888442231214, 6197.225346831646, 6258.655773932477, 6365.490760739222, 6419.2938776735455, 6271.278802937721, 6325.209348831396, 6335.4931605472375, 6340.048992651139, 6346.777862774781, 6132.661461321519, 6135.171422439487, 6161.383861613803, 6316.431521163117, 6322.747090116344, 6012.293125397822, 6153.046277826944, 6171.321154672893, 6180.5673602849165, 6205.100260462298, 6314.273858506331, 6316.62100273985, 6328.303886572381, 6342.52431067681, 6347.96826015867, 6051.667158306335, 6118.345080237214, 6149.8007888461325, 6164.321924588381, 6197.876042479215], "value": 5750.828178319729, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.2128237384932, 503.67009608706286, 515.8271978887072, 531.4685314685315, 536.6806749983758, 34083.4887476688, 34403.13992150198, 39216.41175382368, 41969.37045944313, 41984.58015419845, 34116.48825267623, 42804.57195428045, 42825.14349713004, 42850.571494285046, 43041.0, 8319.916800831974, 9520.952695203521, 9792.853107203398, 9801.16690081344, 9854.113129818315, 9480.81038379232, 9525.999770024151, 9633.27750418718, 9723.513824308757, 9774.16919561838, 8515.872261916076, 9688.321817472779, 9812.969638187995, 9822.950885245564, 9835.65575204868, 7848.372130229568, 9790.971947945434, 9806.166475849597, 9811.11699947001, 9811.90188098117, 9571.042895710443, 9640.180584650314, 9649.710508684751, 9662.516874156334, 9666.275029372755, 39281.803590981865, 39662.40506392407, 39685.60314396848, 39741.80774576767, 39889.0, 2787.3171073087224, 3061.188784971962, 3121.9219519511967, 3205.7114859662743, 3464.22055037617, 29301.853490732625, 29536.70463295361, 29822.403551929056, 30598.082057538308, 30748.8462557688, 2714.2128782653203, 2780.179846945156, 2780.9860950695115, 2788.623535822658, 2828.109145619144, 483633.0, 491503.0, 497462.9999999964, 498143.0, 499646.0, 69781.95327070098, 70199.29800701927, 70335.64832175807, 70507.0, 70512.64743676351, 38302.0, 38988.80505597454, 39265.80367098146, 40419.98950026273, 40567.79716101401, 17401.0, 17502.562435938948, 17655.823441765417, 17687.646247074987, 17701.291948321923, 13330.400131994083, 13380.665483362995, 13437.924966002696, 13451.932740336235, 13465.730685386237, 21854.45363865916, 21972.450688732908, 21973.121075156814, 22069.668954965688, 22175.44561385978, 9040.593173307218, 9112.453252804853, 9141.222996045277, 9188.310876684141, 9189.678361257458, 841.3941961787503, 853.1979938857454, 874.2306770042322, 886.6054605700531, 967.666155176463, 26394.472110557683, 29683.851580741957, 30231.395372092877, 31466.21334466611, 31964.360712786078, 1019.3476175247847, 1046.2676126711333, 1057.0697785948316, 1062.4740753327153, 1095.1622009162998, 12540.623781286577, 13354.5977672343, 14755.852441475663, 15198.240087995557, 15535.84464155344, 629146.0, 674990.0, 675714.0, 676753.0, 677999.0, 58647.53381165505, 58951.705241473515, 58996.70501647464, 59096.0, 59129.81740365169, 1342.9194248345132, 1410.372384289002, 1463.604826696786, 1472.0431719382486, 1475.845036271176, 29156.27109322284, 29223.561646574894, 29455.410891781616, 29828.403431931674, 29854.10437686873, 52930.2060469101, 53577.732111339195, 53767.731161343945, 54004.18993715176, 54063.72968135134, 586375.0, 594984.0, 605526.0, 606056.0, 606369.0, 22859.428514287276, 27079.864600676872, 27263.182104536896, 27395.589066163626, 27612.585811212448, 44227.557724422346, 44917.0, 45379.0, 45659.77170114128, 46121.76939115417, 37817.86546403612, 38065.42901856419, 38177.809110954266, 38469.75000620551, 38786.8363949082, 32800.0, 36984.815075924445, 37148.0, 37728.0, 37745.81127094347, 35513.46729799107, 36250.274994499436, 36690.715824946, 36706.4494032595, 37205.0, 26257.737422626295, 33395.499067514495, 34319.0, 34424.13939651529, 35154.82422587871, 20908.37274881756, 24636.13773517918, 25210.873945630887, 25333.87333063323, 25680.486390272465], "value": 19766.840917691898, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.86727711662354, 392.43293440977783, 392.9115948911493, 397.72391659611344, 414.71591959507737, 19846.801531984675, 20944.89527552364, 21135.365939021834, 21217.469563260925, 21299.25452609159, 20140.89929550354, 20193.19227230909, 20737.481562960933, 20785.376438706862, 20805.0, 5756.222909907151, 6027.156198132265, 6061.45446909778, 6062.878742425149, 6067.0, 5767.279090113736, 5862.149988251701, 5894.911576326359, 5945.791897283596, 5945.88108237835, 5727.513161381266, 5886.058230683092, 5897.7346019429015, 5922.1116832475045, 5925.259342582177, 5614.382417934024, 5802.274715660543, 5835.533157347402, 5919.141724449953, 5978.551608629326, 5749.856253593652, 5821.18503409523, 5831.212786273841, 5831.708414579254, 5847.941520584783, 19018.334358297532, 22094.779052209433, 24547.0, 24834.751652483606, 25285.49429011428, 2069.699893515447, 2106.0, 2167.2089687264033, 2204.437868343562, 2359.1978727232654, 9380.015098414704, 9788.706338809847, 9852.014798520162, 12084.516619335216, 15314.770278445832, 1636.2064398766588, 1744.127936031984, 1766.5848525596475, 1801.5946412057313, 1805.142557285292, 99013.0, 123300.0, 127289.0, 130142.3492882539, 130315.0, 9611.0, 11712.063034957184, 11914.40427978615, 11945.820812687816, 12110.939445302893, 14897.70204595902, 14909.70180596382, 14943.0, 14949.925250373679, 15011.849881501044, 7292.708291668274, 8555.230029297392, 8669.0, 8757.956210218908, 8814.250788682906, 8564.357673174536, 8640.611172497252, 8671.826563468696, 8799.0, 8807.867881981776, 4573.336866154389, 4646.186917289461, 4679.555442232982, 4714.222153344728, 4715.292706094045, 1939.1674867250313, 1954.5113721569608, 1978.3867001229664, 2004.418718571598, 2038.2051000109968, 884.8672699095188, 919.5012130227819, 925.9629614815467, 960.5101398286924, 1032.396048311746, 15150.696986060217, 19078.236870525023, 19997.200111995648, 20321.695174572393, 20375.184992600425, 876.4741155306892, 932.6549176804625, 950.0357137505438, 978.2125389061731, 982.5922242269378, 9913.405195688281, 9979.950100249453, 10502.601726509773, 10505.9474702626, 10515.94742026285, 616365.0, 618971.9051404714, 620182.0, 623235.0, 629041.0, 98420.50789746149, 121584.78415215912, 124404.133937991, 132013.33993329972, 141599.0, 1018.1396719771781, 1041.1046500009986, 1049.2550289294534, 1051.405955635079, 1053.489057806963, 15276.694466110393, 15773.605659858136, 17671.82328176753, 17796.021218833088, 17827.376041838994, 32944.67055329416, 53008.73495632497, 53798.731006344715, 54029.729851350494, 54540.0, 77287.43056840231, 77937.0, 78162.21837781549, 78389.60805195938, 80204.79692804496, 6855.4172895304455, 7376.594287314035, 7384.446166537424, 7784.532928024337, 8311.0, 7357.632118394166, 10743.355398676105, 12549.309787961749, 12614.810777838524, 12903.161294515827, 10411.895881041395, 10971.286866353661, 11083.889161108607, 11101.111911046935, 11438.627364716287, 8715.477071375737, 10490.213234007633, 10884.40135792539, 10998.285111467729, 11133.665990020312, 10789.676309710721, 10926.0, 11285.887141128813, 11353.432328383713, 11692.824607631062, 7445.4043676506835, 7527.924720752722, 7561.0, 7561.6597253122645, 7562.735304264324, 10201.795964080826, 10891.01980821698, 11156.721081973015, 11232.382218978035, 11243.156763242627], "value": 9466.646316372722, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.3221721356538, 1428.6071330384143, 1507.9095254284744, 1922.7019811929147, 1926.5376309685662, 6785.76249831256, 8653.264472519844, 8797.384183107186, 9305.4881981491, 9319.58061887215, 7461.626918654059, 8787.253083487894, 8933.419327743692, 8938.37431379802, 8988.235999940012, 2241.7197850268717, 2297.9659153380962, 2410.132352353156, 2449.008151698565, 2456.0053178462663, 1874.306506592556, 2389.4145934246135, 2400.459896523277, 2419.2379400488876, 2450.901963921441, 2127.850960481338, 2344.4138965258685, 2428.86641234732, 2450.669159663449, 2453.521563295158, 2098.5834561670813, 2373.193114341124, 2406.482606239649, 2414.9857060034738, 2426.46617744096, 1967.8819270843794, 2400.567897778399, 2420.322309753272, 2426.842255253403, 2440.414300567869, 8830.779230519223, 8839.116088391174, 8839.292856571523, 8965.641374345018, 9045.321600879895, 5304.522592966613, 5377.139657654757, 5412.3505179378335, 5487.807926722586, 5529.281193444866, 3397.524346591479, 3619.1133172372674, 3650.0692323457347, 3650.7809531427934, 3696.057505336122, 1000.7498125468633, 1046.712154157605, 1049.0610903241632, 1068.5191663751343, 1072.651388298806, 11871.821922671166, 12046.0, 12057.698557536043, 12058.638240852788, 12071.698207544794, 4847.788052986753, 4913.852584422473, 4957.082939656175, 4989.151844186497, 5011.724355160428, 10153.340032897839, 10154.796904061877, 10156.23828212887, 10165.796684066278, 10190.388576685422, 5413.891722165614, 5489.0, 5502.312210973629, 5532.972335138298, 5557.805476808292, 4488.753118578509, 4821.879453013633, 4898.779554920037, 5046.394432668103, 5082.567981721595, 4094.1749302297703, 4094.3244364679495, 4139.8965025874595, 4152.8338866445, 4156.501219853637, 1344.2203521957242, 1363.6863521389937, 1371.163590209991, 1374.1686279800776, 1375.6835927736677, 916.2120576304372, 922.3497434308796, 943.5612440215333, 945.0785484152896, 967.811276801017, 5408.756605952742, 5762.135679648002, 5905.763769449174, 6123.785667501616, 6127.927612667765, 372.27035011378, 385.7897445891996, 448.96408287336936, 452.3079688077247, 461.82681494439584, 3986.661133803659, 4053.3717273822604, 4071.653909417733, 4085.0400155963325, 4122.855700050543, 10466.21503387249, 10672.839907401396, 10864.674059778219, 10902.400367979679, 11012.724681883017, 10477.266591338686, 10780.029797318122, 10833.891661083446, 10888.020078193, 10992.725181870357, 8174.713885013996, 8227.42408031432, 8275.668973241003, 8293.087760346358, 8374.204450577185, 5764.711764411846, 5930.7924222652, 5969.7313620888035, 5983.072623743326, 6031.36670649593, 9545.188658964065, 9735.367201131903, 10167.084962353423, 10232.02795734404, 10264.0, 8048.436609437284, 9902.653407130716, 10018.39889606626, 10223.284370094021, 10402.323848949794, 6935.479839011993, 7376.7049318026675, 7464.178940316452, 7520.323170914644, 7657.88513172314, 7755.379569634529, 8222.054463736507, 8591.828163436821, 8603.0, 8711.91288087111, 7573.772786816405, 7723.111842138226, 7782.0, 7823.882641760492, 7840.568768717775, 7136.785896423115, 7180.892286615601, 7184.928150718426, 7329.853402931805, 7351.338379545867, 7087.3267039631155, 7136.500444968802, 7188.676509556978, 7202.819929501595, 7236.20401755817, 4885.609151267961, 5041.798328066836, 5099.158638824552, 5147.0, 5149.995750828552, 4273.423087883096, 4975.7063163577, 5006.483790523691, 5062.240663900445, 5153.561947234377], "value": 4543.026487007456, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.16426915310024, 313.9764517661173, 314.7544914966326, 317.1010186122343, 323.3436124666927, 4937.358143441356, 5359.839204823861, 5495.477929596692, 5645.661260324383, 5694.373618901923, 4974.179260422026, 5523.7238138093135, 5562.749676264578, 5580.391706335137, 5582.441755824416, 3544.379733546632, 4002.939955900664, 4104.692148088898, 4238.597333253336, 4249.830006799724, 3973.8013099344917, 4128.318827393491, 4255.382969469425, 4260.403543503912, 4267.957320426803, 4120.072983578686, 4168.937465938014, 4171.91656166878, 4177.16456708658, 4195.5175154857125, 4188.827128404052, 4191.81136848841, 4199.496060472732, 4218.388333691628, 4226.704130710851, 3665.0, 3670.1832592866435, 3670.9632903671154, 3678.3746763050076, 3680.576733675635, 4218.767967761772, 4962.925556116661, 5273.525382715574, 5294.326365185557, 5336.546393556552, 4398.96471434709, 4506.301523263866, 4765.976170119162, 4834.661573689843, 4876.609871210295, 4602.907941841145, 4792.568668819823, 5700.914486282709, 5827.776167004934, 5834.824955251349, 3044.634643842753, 3283.819389933553, 3923.313420151462, 3924.2543916655745, 3930.823112959895, 5860.941390586082, 6054.606450580698, 6071.6642338685415, 6097.146399504073, 6142.938570614281, 3967.9603203968168, 3971.72197946141, 3979.7413168143976, 3999.580044095328, 4006.357393468722, 3619.3847046002243, 3777.8488860445273, 4041.7170798044444, 4042.3936409538214, 4054.412110243998, 4685.344051832746, 4711.434627844612, 4711.552402521755, 4738.668293219442, 4742.359781429532, 5001.699898006132, 5442.619016668875, 5473.562115030788, 5521.046240261441, 5850.385709500526, 1145.0, 1341.6042267531102, 1359.7688392973218, 1370.9314534273246, 1373.4094339433939, 806.4435539477741, 818.378032695156, 826.6938237584602, 841.7685136587487, 846.6232526525637, 4109.91780164395, 4111.383292506148, 4417.955820441819, 4587.857317737361, 4636.86089417318, 4266.530681625019, 4317.676174286941, 4467.329900514948, 4923.070772890124, 5162.277281180638, 3884.3008258513173, 4309.870703878889, 4604.631629469634, 4624.745638989888, 4652.720836749806, 2379.904803807828, 2527.797776177901, 2564.730703276129, 2623.7507436793476, 2663.721413721386, 5227.03986005248, 5271.2868317796865, 5299.5230429261555, 5338.466153384708, 5365.444021233877, 4555.76994211561, 4604.641630718946, 4630.333080091164, 4724.527547245248, 5266.3943646480375, 4762.8333008345235, 5189.40321862983, 5222.660527065728, 5300.535251108176, 5435.83692489226, 4420.756858372821, 4626.537346265347, 5018.895842914553, 5122.359705036871, 5131.640115369355, 4153.792310384529, 4779.211430113992, 4976.30331753562, 4993.675411098267, 4994.675346102492, 4584.518625544403, 4763.642361926834, 5060.318031443341, 5090.650977490894, 5332.320319099471, 2853.915512105387, 3061.4948533492616, 3475.7740746851373, 3488.9127771804892, 3497.370473314828, 4568.132054909556, 4876.658633895593, 5029.59763218949, 5053.191489361684, 5069.898602028013, 5389.649672771257, 5877.942720047949, 5885.822835432845, 5890.764369425175, 5899.498542623754, 5225.145073498853, 5812.808374283346, 5849.473547380756, 5872.500837428866, 5886.793962211302, 4744.359511465908, 4995.350604421402, 5112.79548818058, 5576.386597474194, 5727.479908840097, 5167.6641018333685, 5459.972700136633, 5474.534664553557, 5867.706614669335, 5917.260342457193, 4913.828016019565, 5522.972385138049, 5699.544036476989, 5708.914366284592, 5734.311882574118], "value": 3969.564104330591, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.7388807532949, 679.1849780263685, 705.1960764728213, 722.6639612580149, 735.9227281135483, 12953.417096230669, 13642.045056846026, 14770.409183632655, 15537.37850485982, 15578.376864925403, 13456.058075934665, 16070.116143612126, 16455.177241137924, 16486.83513164868, 16514.33942642293, 723.1683563901503, 920.3695468604011, 932.883389576303, 933.3326671429926, 934.5047125023714, 743.672783975052, 930.7347405989294, 932.9207017403529, 936.7096200177932, 936.7377134402378, 975.9707208783748, 979.2508730820928, 986.1519093579516, 986.5264672956985, 988.6243227573513, 969.2197780786464, 974.1524873360187, 975.9560819763128, 976.9609215631366, 978.4765150644407, 706.8232941764559, 712.6116266634697, 725.3435640745081, 725.941924646027, 729.3326606155362, 15131.243437828174, 15223.695526089416, 15356.616084597863, 15388.538343849703, 15420.614484637974, 12193.329366884818, 12228.205166664138, 12300.200486968319, 12377.009839212842, 12464.002879769596, 9840.36037657557, 10038.749031274276, 10195.4392508412, 10549.683509494727, 10594.41730704811, 3414.419548676706, 3462.3248466549035, 3470.670286322795, 3512.666296701809, 3566.982165089184, 75246.62376688136, 75544.24455755427, 75901.0, 76170.0, 76232.23767762362, 9092.36353455265, 9143.862842057375, 9146.81706365869, 9248.306377021616, 9471.952640236756, 17019.063951482538, 18068.548286292684, 20596.485087872923, 20637.071331790106, 20672.483187920123, 7377.077865266841, 7391.223921488274, 7402.407807375397, 7447.776566703008, 7507.849843003219, 4081.6530594899173, 4119.896630573388, 4146.37804329347, 4168.145530166309, 4168.8332466700995, 3493.5807703075284, 3546.7517273790586, 3571.8749843755345, 3599.6760291573883, 3600.7299452541165, 630.5145038320468, 644.5906849150823, 644.6615526848349, 649.1463725201345, 665.3047565294279, 517.510952150222, 548.0600769680018, 590.3948452835878, 608.2184393054991, 608.7534548507862, 5489.560835133179, 6347.968260158762, 7377.299156580224, 8349.206825351583, 8909.688160914337, 467.6959976015549, 579.3308728418683, 580.6487075319371, 603.2429301226932, 610.2768219659629, 3763.9435408468894, 3833.099221682902, 3859.671927886105, 3955.7626542407547, 4002.1384709932536, 81068.0, 81343.59328203439, 83087.0, 83593.58203208946, 83647.16352836393, 68064.3193568068, 68078.29804254933, 70027.0, 70819.2918070823, 73044.0, 47619.0, 63385.0, 64525.67737161378, 67479.0, 67755.0, 8263.917360826315, 8297.170282971654, 8403.915960840557, 8437.451565648213, 8478.788030299293, 41424.75725728233, 42179.367309491, 42199.367009494264, 42784.358234625885, 43023.70928872139, 53745.73127134339, 56587.71706141443, 57332.71333643305, 58273.4172658268, 58803.41196587979, 10521.736956576147, 10629.9468502657, 10668.786624267626, 10802.513886874953, 11060.281081729661, 11774.52901883915, 11775.23460975034, 11776.234544754563, 11794.705132371759, 12001.459934302802, 9399.825021872266, 10870.347779133277, 10971.067459266053, 11039.558417663204, 11188.83216751733, 10687.412192329497, 11075.833862491907, 11244.887551124384, 11338.829917551078, 11367.943160284476, 10782.137429005817, 10854.240203185702, 10928.562857485611, 11052.171087168334, 11115.44422778874, 8826.779330516789, 9389.812203756022, 9394.906050939404, 9497.097775711296, 9584.185344245818, 8381.245687888118, 9460.668876589287, 9896.703098907044, 9931.404115753077, 10016.649417270652], "value": 6706.185076302446, "unit": "score"}], "name": "dromaeo_css", "value": 7118.8922399300045, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 118.0, 174.0, 102.0, 160.0, 156.0, 155.0, 119.0, 115.0], "value": 133.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 125.0, 151.0, 242.0, 120.0, 144.0, 136.0, 130.0, 168.0, 120.0], "value": 149.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [283.0, 275.0, 226.0, 203.0, 199.0, 201.0, 198.0, 197.0, 243.0, 199.0], "value": 222.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 81.0, 78.0, 177.0, 57.0, 58.0, 64.0, 58.0, 140.0, 50.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 95.0, 165.0, 112.0, 114.0, 96.0, 128.0, 99.0, 103.0, 125.0], "value": 111.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 105.0, 128.0, 92.0, 89.0, 91.0, 122.0, 108.0, 89.0], "value": 100.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 150.0, 136.0, 97.0, 123.0, 97.0, 97.0, 97.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 90.0, 91.0, 90.0, 90.0, 91.0, 90.0, 90.0, 93.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 62.0, 63.0, 68.0, 75.0, 176.0, 100.0, 61.0], "value": 78.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 78.0, 108.0, 68.0, 68.0, 85.0, 105.0, 102.0, 104.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 113.0, 109.0, 107.0, 96.0, 86.0, 86.0, 85.0, 91.0, 88.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 138.0, 139.0, 138.0, 145.0, 143.0, 138.0, 219.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 187.0, 234.0, 172.0, 170.0, 169.0, 173.0, 173.0, 166.0, 174.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 66.0, 62.0, 61.0, 74.0, 96.0, 86.0, 85.0], "value": 73.0, "unit": "score"}], "name": "kraken", "value": 1658.7000000000003, "alertThreshold": 2.0}]} 17:02:08 INFO - Return code: 0 17:02:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:02:08 ERROR - # TBPL SUCCESS # 17:02:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:02:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:02:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:02:08 INFO - 2016-05-11 17:02:08.485 screenresolution[1971:14921] starting screenresolution argv=screenresolution get 17:02:08 INFO - 2016-05-11 17:02:08.487 screenresolution[1971:14921] Display 0: 1600x1200x32@60 17:02:08 INFO - 2016-05-11 17:02:08.493 screenresolution[1972:14924] starting screenresolution argv=screenresolution list 17:02:08 INFO - Available Modes on Display 0 17:02:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:02:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:02:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:02:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:02:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:02:08 INFO - Intel Iris: 17:02:08 INFO - Chipset Model: Intel Iris 17:02:08 INFO - Type: GPU 17:02:08 INFO - Bus: Built-In 17:02:08 INFO - VRAM (Dynamic, Max): 1536 MB 17:02:08 INFO - Vendor: Intel (0x8086) 17:02:08 INFO - Device ID: 0x0a2e 17:02:08 INFO - Revision ID: 0x0009 17:02:08 INFO - Displays: 17:02:08 INFO - Display: 17:02:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:02:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:02:08 INFO - Display Serial Number: 1600x1200 60 17:02:08 INFO - Main Display: Yes 17:02:08 INFO - Mirror: Off 17:02:08 INFO - Online: Yes 17:02:08 INFO - Rotation: Supported 17:02:08 INFO - Return code: 0 17:02:08 INFO - Running post-action listener: _resource_record_post_action 17:02:08 INFO - ##### 17:02:08 INFO - ##### Finished run-tests step (success) 17:02:08 INFO - ##### 17:02:08 INFO - Running post-run listener: _resource_record_post_run 17:02:09 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 610304; Write bytes: 698532864; Read time: 12; Write time: 2087 17:02:09 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 385860096; Write bytes: 383536640; Read time: 29425; Write time: 746 17:02:09 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 593920; Write bytes: 297702912; Read time: 11; Write time: 1302 17:02:09 INFO - Running post-run listener: _upload_blobber_files 17:02:09 INFO - Blob upload gear active. 17:02:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:02:09 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 17:02:09 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'] 17:02:09 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 17:02:10 INFO - (blobuploader) - INFO - Open directory for files ... 17:02:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:02:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:02:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:10 INFO - (blobuploader) - INFO - Done attempting. 17:02:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:02:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:02:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:11 INFO - (blobuploader) - INFO - Done attempting. 17:02:11 INFO - (blobuploader) - INFO - Iteration through files over. 17:02:11 INFO - Return code: 0 17:02:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:02:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:02:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84f1999c45d577638c7b1368fb0a30b987a04c0a550043dfc97c42cf1018f15fb785941b7b428a776f6f35bd375c15ea11525cb5e1feb5cb31f30ed2f5ce8a94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14dd9510954d34c6c85d861440222d446774323c4bd3fb6de9f752b36eb9458a5ed143784cafd312d829e5a1709739d0c9b1567bd046ac59be1110b85dc23913"} 17:02:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:02:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:02:11 INFO - Contents: 17:02:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84f1999c45d577638c7b1368fb0a30b987a04c0a550043dfc97c42cf1018f15fb785941b7b428a776f6f35bd375c15ea11525cb5e1feb5cb31f30ed2f5ce8a94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14dd9510954d34c6c85d861440222d446774323c4bd3fb6de9f752b36eb9458a5ed143784cafd312d829e5a1709739d0c9b1567bd046ac59be1110b85dc23913"} 17:02:11 INFO - Running post-run listener: copy_logs_to_upload_dir 17:02:11 INFO - Copying logs to upload dir... 17:02:11 INFO - mkdir: /builds/slave/test/build/upload/logs 17:02:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.683671 ========= master_lag: 19.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-11 17:02:31.188714) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 17:02:31.191982) ========= 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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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/84f1999c45d577638c7b1368fb0a30b987a04c0a550043dfc97c42cf1018f15fb785941b7b428a776f6f35bd375c15ea11525cb5e1feb5cb31f30ed2f5ce8a94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14dd9510954d34c6c85d861440222d446774323c4bd3fb6de9f752b36eb9458a5ed143784cafd312d829e5a1709739d0c9b1567bd046ac59be1110b85dc23913"} build_url:https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011746 build_url: 'https://queue.taskcluster.net/v1/task/Oqt585BfTaWUT9HWYaoxag/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/84f1999c45d577638c7b1368fb0a30b987a04c0a550043dfc97c42cf1018f15fb785941b7b428a776f6f35bd375c15ea11525cb5e1feb5cb31f30ed2f5ce8a94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14dd9510954d34c6c85d861440222d446774323c4bd3fb6de9f752b36eb9458a5ed143784cafd312d829e5a1709739d0c9b1567bd046ac59be1110b85dc23913"}' ========= master_lag: 0.18 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 17:02:31.385407) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 17:02:31.385729) ========= 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.NoI2LGOQ3u/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.ZWHscsAKcs/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.005092 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 17:02:31.405860) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 17:02:31.406131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 17:02:31.406390) ========= ========= Total master_lag: 19.57 =========