builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1462778664.94 results: success (0) buildid: 20160508232942 builduid: 630289a7b1ca42f39c0d3611577e95bb revision: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:24.944302) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:24.944964) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:24.945449) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:24.965720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:24.966246) ========= 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.942lBzFEpv/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.yZ25DLEE96/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-09 00:24:24-- 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% 140M=0s 2016-05-09 00:24:25 (140 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125535 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:25.111409) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:25.111734) ========= 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.942lBzFEpv/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.yZ25DLEE96/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.030777 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:25.160486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 00:24:25.160838) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 77c1d14c1e1f389a6e4dffd50e401cf739461a82 --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 77c1d14c1e1f389a6e4dffd50e401cf739461a82 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.942lBzFEpv/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.yZ25DLEE96/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-09 00:24:25,224 truncating revision to first 12 chars 2016-05-09 00:24:25,224 Setting DEBUG logging. 2016-05-09 00:24:25,224 attempt 1/10 2016-05-09 00:24:25,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/77c1d14c1e1f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 00:24:26,692 unpacking tar archive at: fx-team-77c1d14c1e1f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.710921 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 00:24:26.889352) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:26.889729) ========= script_repo_revision: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:26.890120) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:26.890409) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 00:24:26.904544) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-09 00:24:26.905080) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.942lBzFEpv/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.yZ25DLEE96/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:24:27 INFO - MultiFileLogger online at 20160509 00:24:27 in /builds/slave/test 00:24:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 00:24:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:24:27 INFO - {'append_to_log': False, 00:24:27 INFO - 'base_work_dir': '/builds/slave/test', 00:24:27 INFO - 'blob_upload_branch': 'Fx-Team', 00:24:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:24:27 INFO - 'branch': 'Fx-Team', 00:24:27 INFO - 'buildbot_json_path': 'buildprops.json', 00:24:27 INFO - 'config_files': ('talos/mac_config.py',), 00:24:27 INFO - 'default_actions': ('clobber', 00:24:27 INFO - 'read-buildbot-config', 00:24:27 INFO - 'download-and-extract', 00:24:27 INFO - 'populate-webroot', 00:24:27 INFO - 'create-virtualenv', 00:24:27 INFO - 'install', 00:24:27 INFO - 'run-tests'), 00:24:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:24:27 INFO - 'download_minidump_stackwalk': True, 00:24:27 INFO - 'download_symbols': 'ondemand', 00:24:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:24:27 INFO - 'tooltool.py': '/tools/tooltool.py', 00:24:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:24:27 INFO - '/tools/misc-python/virtualenv.py')}, 00:24:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:24:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:24:27 INFO - 'installer_path': 'installer.exe', 00:24:27 INFO - 'log_level': 'info', 00:24:27 INFO - 'log_name': 'talos', 00:24:27 INFO - 'log_to_console': True, 00:24:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:24:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:24:27 INFO - 'opt_config_files': (), 00:24:27 INFO - 'pip_index': False, 00:24:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:24:27 INFO - 'cmd': ('bash', 00:24:27 INFO - '-c', 00:24:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:24:27 INFO - 'enabled': True, 00:24:27 INFO - 'halt_on_failure': False, 00:24:27 INFO - 'name': 'check_screen_resolution'},), 00:24:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:24:27 INFO - 'cmd': ('bash', 00:24:27 INFO - '-c', 00:24:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:24:27 INFO - 'enabled': True, 00:24:27 INFO - 'halt_on_failure': False, 00:24:27 INFO - 'name': 'check_screen_resolution'},), 00:24:27 INFO - 'run_cmd_checks_enabled': True, 00:24:27 INFO - 'sps_profile': False, 00:24:27 INFO - 'sps_profile_interval': 0, 00:24:27 INFO - 'suite': 'dromaeojs', 00:24:27 INFO - 'system_bits': '32', 00:24:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:24:27 INFO - 'title': 't-yosemite-r7-0110', 00:24:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:24:27 INFO - 'use_talos_json': True, 00:24:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:24:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:24:27 INFO - 'work_dir': 'build'} 00:24:27 INFO - ##### 00:24:27 INFO - ##### Running clobber step. 00:24:27 INFO - ##### 00:24:27 INFO - Running pre-action listener: _resource_record_pre_action 00:24:27 INFO - Running main action method: clobber 00:24:27 INFO - rmtree: /builds/slave/test/build 00:24:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:24:28 INFO - Running post-action listener: _resource_record_post_action 00:24:28 INFO - ##### 00:24:28 INFO - ##### Finished clobber step (success) 00:24:28 INFO - ##### 00:24:28 INFO - ##### 00:24:28 INFO - ##### Running read-buildbot-config step. 00:24:28 INFO - ##### 00:24:28 INFO - Running pre-action listener: _resource_record_pre_action 00:24:28 INFO - Running main action method: read_buildbot_config 00:24:28 INFO - Using buildbot properties: 00:24:28 INFO - { 00:24:28 INFO - "project": "", 00:24:28 INFO - "product": "firefox", 00:24:28 INFO - "script_repo_revision": "production", 00:24:28 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 00:24:28 INFO - "repository": "", 00:24:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 00:24:28 INFO - "buildid": "20160508232942", 00:24:28 INFO - "pgo_build": "False", 00:24:28 INFO - "basedir": "/builds/slave/test", 00:24:28 INFO - "buildnumber": 3, 00:24:28 INFO - "slavename": "t-yosemite-r7-0110", 00:24:28 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 00:24:28 INFO - "platform": "yosemite_r7", 00:24:28 INFO - "scheduler": "tests-fx-team-macosx64-talos", 00:24:28 INFO - "branch": "fx-team", 00:24:28 INFO - "repo_path": "integration/fx-team", 00:24:28 INFO - "revision": "77c1d14c1e1f389a6e4dffd50e401cf739461a82", 00:24:28 INFO - "stage_platform": "macosx64", 00:24:28 INFO - "builduid": "630289a7b1ca42f39c0d3611577e95bb", 00:24:28 INFO - "slavebuilddir": "test" 00:24:28 INFO - } 00:24:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 00:24:28 INFO - Running post-action listener: _resource_record_post_action 00:24:28 INFO - ##### 00:24:28 INFO - ##### Finished read-buildbot-config step (success) 00:24:28 INFO - ##### 00:24:28 INFO - ##### 00:24:28 INFO - ##### Running download-and-extract step. 00:24:28 INFO - ##### 00:24:28 INFO - Running pre-action listener: _resource_record_pre_action 00:24:28 INFO - Running main action method: download_and_extract 00:24:28 INFO - mkdir: /builds/slave/test/build/tests 00:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:28 INFO - https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:24:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:24:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:24:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:30 INFO - Downloaded 1199 bytes. 00:24:30 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 00:24:30 INFO - Using the following test package requirements: 00:24:30 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:24:30 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 00:24:30 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 00:24:30 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'jsshell-mac.zip'], 00:24:30 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 00:24:30 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:24:30 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 00:24:30 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 00:24:30 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 00:24:30 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:24:30 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 00:24:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:24: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')]} 00:24:30 INFO - https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:24:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:24:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:24:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:32 INFO - Downloaded 18145011 bytes. 00:24:32 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'] 00:24:32 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 00:24:35 INFO - Return code: 0 00:24:35 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 00:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:35 INFO - https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:36 INFO - Downloaded 18145011 bytes. 00:24:36 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'] 00:24:36 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 00:24:39 INFO - Return code: 0 00:24:39 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')]} 00:24:39 INFO - https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:24:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:24:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:24:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:41 INFO - Downloaded 10840152 bytes. 00:24:41 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'] 00:24:41 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 00:24:42 INFO - Return code: 0 00:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:42 INFO - https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 00:24:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:24:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:24:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 00:24:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYfzLBCBTuimzmybRB_gcQ/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 00:24:45 INFO - Downloaded 110317891 bytes. 00:24:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:24:45 INFO - mkdir: /builds/slave/test/properties 00:24:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:24:45 INFO - Writing to file /builds/slave/test/properties/build_url 00:24:45 INFO - Contents: 00:24:45 INFO - build_url:https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:24:45 INFO - Running post-action listener: _resource_record_post_action 00:24:45 INFO - Running post-action listener: set_extra_try_arguments 00:24:45 INFO - ##### 00:24:45 INFO - ##### Finished download-and-extract step (success) 00:24:45 INFO - ##### 00:24:45 INFO - ##### 00:24:45 INFO - ##### Running populate-webroot step. 00:24:45 INFO - ##### 00:24:45 INFO - Running pre-action listener: _resource_record_pre_action 00:24:45 INFO - Running main action method: populate_webroot 00:24:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:24:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:24:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:24:45 INFO - '--tppagecycles', 00:24:45 INFO - '7'], 00:24:45 INFO - 'tests': ['tsvgm']}}, 00:24:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:24:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:24:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:24:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:24:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:24:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:24:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['damp', 'tps']}, 00:24:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'tests': ['damp', 'tps']}, 00:24:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['dromaeo_dom']}, 00:24:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:24:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['a11yr', 00:24:45 INFO - 'ts_paint', 00:24:45 INFO - 'tpaint', 00:24:45 INFO - 'sessionrestore', 00:24:45 INFO - 'sessionrestore_no_auto_restore', 00:24:45 INFO - 'tabpaint']}, 00:24:45 INFO - 'other-e10s': {'tests': ['a11yr', 00:24:45 INFO - 'ts_paint', 00:24:45 INFO - 'tpaint', 00:24:45 INFO - 'sessionrestore', 00:24:45 INFO - 'sessionrestore_no_auto_restore', 00:24:45 INFO - 'tabpaint']}, 00:24:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:24:45 INFO - 'ts_paint', 00:24:45 INFO - 'tpaint', 00:24:45 INFO - 'sessionrestore', 00:24:45 INFO - 'sessionrestore_no_auto_restore', 00:24:45 INFO - 'tabpaint']}, 00:24:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:24:45 INFO - 'ts_paint', 00:24:45 INFO - 'tpaint', 00:24:45 INFO - 'sessionrestore', 00:24:45 INFO - 'sessionrestore_no_auto_restore', 00:24:45 INFO - 'tabpaint']}, 00:24:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['a11yr', 00:24:45 INFO - 'ts_paint', 00:24:45 INFO - 'tpaint', 00:24:45 INFO - 'sessionrestore', 00:24:45 INFO - 'sessionrestore_no_auto_restore', 00:24:45 INFO - 'tabpaint']}, 00:24:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['a11yr', 00:24:45 INFO - 'ts_paint', 00:24:45 INFO - 'tpaint', 00:24:45 INFO - 'sessionrestore', 00:24:45 INFO - 'sessionrestore_no_auto_restore', 00:24:45 INFO - 'tabpaint']}, 00:24:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['tsvgx', 00:24:45 INFO - 'tsvgr_opacity', 00:24:45 INFO - 'tart', 00:24:45 INFO - 'tscrollx', 00:24:45 INFO - 'cart']}, 00:24:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:24:45 INFO - 'tsvgr_opacity', 00:24:45 INFO - 'tart', 00:24:45 INFO - 'tscrollx', 00:24:45 INFO - 'cart']}, 00:24:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'talos_options': ['--disable-e10s'], 00:24:45 INFO - 'tests': ['tp5o']}, 00:24:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'tests': ['tp5o']}, 00:24:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'talos_options': ['--disable-e10s', 00:24:45 INFO - '--xperf_path', 00:24:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:24:45 INFO - 'tests': ['tp5n']}, 00:24:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:24:45 INFO - 'talos_options': ['--xperf_path', 00:24:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:24:45 INFO - 'tests': ['tp5n']}}, 00:24:45 INFO - 'talos.zip': {'path': '', 00:24:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:24:45 INFO - Running post-action listener: _resource_record_post_action 00:24:45 INFO - ##### 00:24:45 INFO - ##### Finished populate-webroot step (success) 00:24:45 INFO - ##### 00:24:45 INFO - ##### 00:24:45 INFO - ##### Running create-virtualenv step. 00:24:45 INFO - ##### 00:24:45 INFO - Running pre-action listener: _resource_record_pre_action 00:24:45 INFO - Running main action method: create_virtualenv 00:24:45 INFO - Creating virtualenv /builds/slave/test/build/venv 00:24:45 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 00:24:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:24:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:24:45 INFO - Using real prefix '/tools/python27' 00:24:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:24:46 INFO - Installing distribute.............................................................................................................................................................................................done. 00:24:49 INFO - Installing pip.................done. 00:24:49 INFO - Return code: 0 00:24:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:49 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:24:49 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 00:24:49 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 00:24:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:24:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:24:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:24:49 INFO - 'HOME': '/Users/cltbld', 00:24:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:24:49 INFO - 'LOGNAME': 'cltbld', 00:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:49 INFO - 'MOZ_NO_REMOTE': '1', 00:24:49 INFO - 'NO_EM_RESTART': '1', 00:24:49 INFO - 'PAGER': '/bin/cat', 00:24:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:49 INFO - 'PWD': '/builds/slave/test', 00:24:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:24:49 INFO - 'SHELL': '/bin/bash', 00:24:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:24:49 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:24:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:24:49 INFO - 'USER': 'cltbld', 00:24:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:24:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:49 INFO - 'XPC_FLAGS': '0x0', 00:24:49 INFO - 'XPC_SERVICE_NAME': '0', 00:24:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:24:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:49 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 00:24:49 INFO - Cleaning up... 00:24:49 INFO - Return code: 0 00:24:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 00:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:49 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:24:49 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 00:24:49 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 00:24:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:24:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:24:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:24:49 INFO - 'HOME': '/Users/cltbld', 00:24:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:24:49 INFO - 'LOGNAME': 'cltbld', 00:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:49 INFO - 'MOZ_NO_REMOTE': '1', 00:24:49 INFO - 'NO_EM_RESTART': '1', 00:24:49 INFO - 'PAGER': '/bin/cat', 00:24:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:49 INFO - 'PWD': '/builds/slave/test', 00:24:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:24:49 INFO - 'SHELL': '/bin/bash', 00:24:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:24:49 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:24:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:24:49 INFO - 'USER': 'cltbld', 00:24:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:24:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:49 INFO - 'XPC_FLAGS': '0x0', 00:24:49 INFO - 'XPC_SERVICE_NAME': '0', 00:24:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:50 INFO - Downloading/unpacking psutil>=0.7.1 00:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:24:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:24:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:24:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:24:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:24:52 INFO - Installing collected packages: psutil 00:24:52 INFO - Running setup.py install for psutil 00:24:52 INFO - building 'psutil._psutil_osx' extension 00:24:52 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 00:24:53 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 00:24:53 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 00:24:53 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 00:24:53 INFO - building 'psutil._psutil_posix' extension 00:24:53 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 00:24:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:24:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:24:53 INFO - ^ 00:24:53 INFO - 1 warning generated. 00:24:53 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 00:24:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:24:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:24:53 INFO - Successfully installed psutil 00:24:53 INFO - Cleaning up... 00:24:54 INFO - Return code: 0 00:24:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 00:24:54 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')]} 00:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:54 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')]} 00:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:54 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:24:54 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 00:24:54 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 00:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:24:54 INFO - 'HOME': '/Users/cltbld', 00:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:24:54 INFO - 'LOGNAME': 'cltbld', 00:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:54 INFO - 'MOZ_NO_REMOTE': '1', 00:24:54 INFO - 'NO_EM_RESTART': '1', 00:24:54 INFO - 'PAGER': '/bin/cat', 00:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:54 INFO - 'PWD': '/builds/slave/test', 00:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:24:54 INFO - 'SHELL': '/bin/bash', 00:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:24:54 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:24:54 INFO - 'USER': 'cltbld', 00:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:54 INFO - 'XPC_FLAGS': '0x0', 00:24:54 INFO - 'XPC_SERVICE_NAME': '0', 00:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:24: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 00:24: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 00:24: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 00:24: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 00:24: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 00:24: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 00:24:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:24:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:24:56 INFO - Installing collected packages: mozsystemmonitor 00:24:56 INFO - Running setup.py install for mozsystemmonitor 00:24:56 INFO - Successfully installed mozsystemmonitor 00:24:56 INFO - Cleaning up... 00:24:56 INFO - Return code: 0 00:24:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:24:56 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:24:56 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 00:24:56 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 00:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:24:56 INFO - 'HOME': '/Users/cltbld', 00:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:24:56 INFO - 'LOGNAME': 'cltbld', 00:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:24:56 INFO - 'MOZ_NO_REMOTE': '1', 00:24:56 INFO - 'NO_EM_RESTART': '1', 00:24:56 INFO - 'PAGER': '/bin/cat', 00:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:24:56 INFO - 'PWD': '/builds/slave/test', 00:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:24:56 INFO - 'SHELL': '/bin/bash', 00:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:24:56 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:24:56 INFO - 'USER': 'cltbld', 00:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:24:56 INFO - 'XPC_FLAGS': '0x0', 00:24:56 INFO - 'XPC_SERVICE_NAME': '0', 00:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:24:57 INFO - Downloading/unpacking blobuploader==1.2.4 00:24:57 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 00:24:57 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 00:24:57 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 00:24:57 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 00:24:57 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 00:24:57 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 00:24:59 INFO - Downloading blobuploader-1.2.4.tar.gz 00:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:24:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:24:59 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 00:24:59 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 00:24:59 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 00:24:59 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 00:24:59 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 00:24:59 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 00:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:25:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:25:00 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 00:25:00 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 00:25:00 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 00:25:00 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 00:25:00 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 00:25:00 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 00:25:00 INFO - Downloading docopt-0.6.1.tar.gz 00:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:25:00 INFO - Installing collected packages: blobuploader, requests, docopt 00:25:00 INFO - Running setup.py install for blobuploader 00:25:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:25:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:25:01 INFO - Running setup.py install for requests 00:25:01 INFO - Running setup.py install for docopt 00:25:01 INFO - Successfully installed blobuploader requests docopt 00:25:01 INFO - Cleaning up... 00:25:01 INFO - Return code: 0 00:25:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:01 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:25:01 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 00:25:01 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 00:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:25:01 INFO - 'HOME': '/Users/cltbld', 00:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:25:01 INFO - 'LOGNAME': 'cltbld', 00:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:01 INFO - 'MOZ_NO_REMOTE': '1', 00:25:01 INFO - 'NO_EM_RESTART': '1', 00:25:01 INFO - 'PAGER': '/bin/cat', 00:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:01 INFO - 'PWD': '/builds/slave/test', 00:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:25:01 INFO - 'SHELL': '/bin/bash', 00:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:25:01 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:25:01 INFO - 'USER': 'cltbld', 00:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:01 INFO - 'XPC_FLAGS': '0x0', 00:25:01 INFO - 'XPC_SERVICE_NAME': '0', 00:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-XuxUWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-m9uiEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-MniUlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xdJknO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-gR9nyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-rK_XDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-40coZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-aXzaDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-fNXaDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:25:02 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-qcR1Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:25:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-1mGg8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:25:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-8CJEUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:25:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dxa2ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:25:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-w9RcLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:25:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0hGTeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:25:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-9fKPjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:25:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QMNdQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:25:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:25:03 INFO - Running setup.py install for manifestparser 00:25:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:25:03 INFO - Running setup.py install for mozcrash 00:25:04 INFO - Running setup.py install for mozdebug 00:25:04 INFO - Running setup.py install for mozdevice 00:25:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Running setup.py install for mozfile 00:25:04 INFO - Running setup.py install for mozhttpd 00:25:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Running setup.py install for mozinfo 00:25:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Running setup.py install for mozInstall 00:25:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:25:04 INFO - Running setup.py install for mozleak 00:25:05 INFO - Running setup.py install for mozlog 00:25:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:25:05 INFO - Running setup.py install for moznetwork 00:25:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:25:05 INFO - Running setup.py install for mozprocess 00:25:05 INFO - Running setup.py install for mozprofile 00:25:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:25:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:25:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:25:05 INFO - Running setup.py install for mozrunner 00:25:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:25:05 INFO - Running setup.py install for mozscreenshot 00:25:06 INFO - Running setup.py install for moztest 00:25:06 INFO - Running setup.py install for mozversion 00:25:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:25:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:25:06 INFO - Cleaning up... 00:25:06 INFO - Return code: 0 00:25:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:25:06 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')]} 00:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:06 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')]} 00:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:06 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:25:06 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 00:25:06 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 00:25:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:25:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:25:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:25:06 INFO - 'HOME': '/Users/cltbld', 00:25:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:25:06 INFO - 'LOGNAME': 'cltbld', 00:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:06 INFO - 'MOZ_NO_REMOTE': '1', 00:25:06 INFO - 'NO_EM_RESTART': '1', 00:25:06 INFO - 'PAGER': '/bin/cat', 00:25:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:06 INFO - 'PWD': '/builds/slave/test', 00:25:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:25:06 INFO - 'SHELL': '/bin/bash', 00:25:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:25:06 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:25:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:25:06 INFO - 'USER': 'cltbld', 00:25:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:25:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:06 INFO - 'XPC_FLAGS': '0x0', 00:25:06 INFO - 'XPC_SERVICE_NAME': '0', 00:25:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:25:06 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-3Aqs8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:25:06 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)) 00:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:25:06 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-poOrHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:25:06 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)) 00:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:25:06 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-eozK36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:25:06 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)) 00:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:25:06 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ghwPhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-O9RQ9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-aNIpiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Zi1cBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-zr9o02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-r01Mjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-tRR7Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-bvSfIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-8oAbqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Sp3TfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:25:07 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)) 00:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:25:07 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-vJzU3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:25:08 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)) 00:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:25:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6tlzl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:25:08 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)) 00:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:25:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iuuQu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:25:08 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)) 00:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:25:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6X7IFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:25:08 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)) 00:25:08 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)) 00:25:08 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)) 00:25:08 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)) 00:25:08 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)) 00:25:08 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)) 00:25:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:25:10 INFO - Downloading blessings-1.6.tar.gz 00:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:25:11 INFO - Installing collected packages: blessings 00:25:11 INFO - Running setup.py install for blessings 00:25:11 INFO - Successfully installed blessings 00:25:11 INFO - Cleaning up... 00:25:11 INFO - Return code: 0 00:25:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:25:11 INFO - Reading from file tmpfile_stdout 00:25:11 INFO - Current package versions: 00:25:11 INFO - blessings == 1.6 00:25:11 INFO - blobuploader == 1.2.4 00:25:11 INFO - docopt == 0.6.1 00:25:11 INFO - manifestparser == 1.1 00:25:11 INFO - mozInstall == 1.12 00:25:11 INFO - mozcrash == 0.17 00:25:11 INFO - mozdebug == 0.1 00:25:11 INFO - mozdevice == 0.48 00:25:11 INFO - mozfile == 1.2 00:25:11 INFO - mozhttpd == 0.7 00:25:11 INFO - mozinfo == 0.9 00:25:11 INFO - mozleak == 0.1 00:25:11 INFO - mozlog == 3.1 00:25:11 INFO - moznetwork == 0.27 00:25:11 INFO - mozprocess == 0.22 00:25:11 INFO - mozprofile == 0.28 00:25:11 INFO - mozrunner == 6.11 00:25:11 INFO - mozscreenshot == 0.1 00:25:11 INFO - mozsystemmonitor == 0.0 00:25:11 INFO - moztest == 0.7 00:25:11 INFO - mozversion == 1.4 00:25:11 INFO - psutil == 3.1.1 00:25:11 INFO - requests == 1.2.3 00:25:11 INFO - wsgiref == 0.1.2 00:25:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:11 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:25:11 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 00:25:11 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 00:25:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:25:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:25:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:25:11 INFO - 'HOME': '/Users/cltbld', 00:25:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:25:11 INFO - 'LOGNAME': 'cltbld', 00:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:11 INFO - 'MOZ_NO_REMOTE': '1', 00:25:11 INFO - 'NO_EM_RESTART': '1', 00:25:11 INFO - 'PAGER': '/bin/cat', 00:25:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:11 INFO - 'PWD': '/builds/slave/test', 00:25:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:25:11 INFO - 'SHELL': '/bin/bash', 00:25:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:25:11 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:25:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:25:11 INFO - 'USER': 'cltbld', 00:25:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:25:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:11 INFO - 'XPC_FLAGS': '0x0', 00:25:11 INFO - 'XPC_SERVICE_NAME': '0', 00:25:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 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)) 00:25:11 INFO - Cleaning up... 00:25:11 INFO - Return code: 0 00:25:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:25:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:25:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:25:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:25:11 INFO - 'HOME': '/Users/cltbld', 00:25:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:25:11 INFO - 'LOGNAME': 'cltbld', 00:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:11 INFO - 'MOZ_NO_REMOTE': '1', 00:25:11 INFO - 'NO_EM_RESTART': '1', 00:25:11 INFO - 'PAGER': '/bin/cat', 00:25:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:11 INFO - 'PWD': '/builds/slave/test', 00:25:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:25:11 INFO - 'SHELL': '/bin/bash', 00:25:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:25:11 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:25:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:25:11 INFO - 'USER': 'cltbld', 00:25:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:25:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:11 INFO - 'XPC_FLAGS': '0x0', 00:25:11 INFO - 'XPC_SERVICE_NAME': '0', 00:25:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:25:12 INFO - Downloading/unpacking jsonschema 00:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:25:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:25:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:25:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:25:14 INFO - Downloading/unpacking functools32 (from jsonschema) 00:25:14 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 00:25:14 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 00:25:14 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 00:25:14 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 00:25:14 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 00:25:14 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 00:25:15 INFO - Downloading functools32-3.2.3-2.tar.gz 00:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:25:15 INFO - warning: no files found matching '*.txt' 00:25:15 INFO - no previously-included directories found matching 'build' 00:25:15 INFO - no previously-included directories found matching 'dist' 00:25:15 INFO - no previously-included directories found matching '.git*' 00:25:15 INFO - Installing collected packages: jsonschema, functools32 00:25:15 INFO - Running setup.py install for functools32 00:25:15 INFO - warning: no files found matching '*.txt' 00:25:15 INFO - no previously-included directories found matching 'build' 00:25:15 INFO - no previously-included directories found matching 'dist' 00:25:15 INFO - no previously-included directories found matching '.git*' 00:25:15 INFO - Successfully installed jsonschema functools32 00:25:15 INFO - Cleaning up... 00:25:15 INFO - Return code: 0 00:25:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:25:15 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 0x10cff3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cff9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85105ac480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, '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.942lBzFEpv/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.yZ25DLEE96/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 00:25:15 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 00:25:15 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 00:25:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:25:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:25:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:25:15 INFO - 'HOME': '/Users/cltbld', 00:25:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:25:15 INFO - 'LOGNAME': 'cltbld', 00:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:15 INFO - 'MOZ_NO_REMOTE': '1', 00:25:15 INFO - 'NO_EM_RESTART': '1', 00:25:15 INFO - 'PAGER': '/bin/cat', 00:25:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:15 INFO - 'PWD': '/builds/slave/test', 00:25:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:25:15 INFO - 'SHELL': '/bin/bash', 00:25:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:25:15 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:25:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:25:15 INFO - 'USER': 'cltbld', 00:25:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:25:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:15 INFO - 'XPC_FLAGS': '0x0', 00:25:15 INFO - 'XPC_SERVICE_NAME': '0', 00:25:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:25:15 INFO - Downloading/unpacking flake8 00:25:15 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 00:25:15 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 00:25:15 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 00:25:15 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 00:25:15 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 00:25:15 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 00:25:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:25:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:25:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:18 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 00:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:25:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:25:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:25:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:25:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:25:19 INFO - no previously-included directories found matching 'docs/_build' 00:25:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:25:19 INFO - Running setup.py install for pep8 00:25:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:25:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:25:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:25:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:25:19 INFO - no previously-included directories found matching 'docs/_build' 00:25:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:25:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:25:19 INFO - Cleaning up... 00:25:19 INFO - Return code: 0 00:25:19 INFO - Running post-action listener: _resource_record_post_action 00:25:19 INFO - Running post-action listener: _start_resource_monitoring 00:25:19 INFO - Starting resource monitoring. 00:25:19 INFO - ##### 00:25:19 INFO - ##### Finished create-virtualenv step (success) 00:25:19 INFO - ##### 00:25:19 INFO - ##### 00:25:19 INFO - ##### Running install step. 00:25:19 INFO - ##### 00:25:19 INFO - Running pre-action listener: _resource_record_pre_action 00:25:19 INFO - Running main action method: install 00:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:25:20 INFO - Reading from file tmpfile_stdout 00:25:20 INFO - Detecting whether we're running mozinstall >=1.0... 00:25:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:25:20 INFO - Reading from file tmpfile_stdout 00:25:20 INFO - Output received: 00:25:20 INFO - Usage: mozinstall [options] installer 00:25:20 INFO - Options: 00:25:20 INFO - -h, --help show this help message and exit 00:25:20 INFO - -d DEST, --destination=DEST 00:25:20 INFO - Directory to install application into. [default: 00:25:20 INFO - "/builds/slave/test"] 00:25:20 INFO - --app=APP Application being installed. [default: firefox] 00:25:20 INFO - mkdir: /builds/slave/test/build/application 00:25:20 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'] 00:25:20 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 00:25:53 INFO - Reading from file tmpfile_stdout 00:25:53 INFO - Output received: 00:25:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:25:53 INFO - Running post-action listener: _resource_record_post_action 00:25:53 INFO - ##### 00:25:53 INFO - ##### Finished install step (success) 00:25:53 INFO - ##### 00:25:53 INFO - ##### 00:25:53 INFO - ##### Running run-tests step. 00:25:53 INFO - ##### 00:25:53 INFO - Running pre-action listener: _resource_record_pre_action 00:25:53 INFO - Running main action method: run_tests 00:25:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:25:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:25:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:25:53 INFO - 2016-05-09 00:25:53.568 screenresolution[1642:7520] starting screenresolution argv=screenresolution get 00:25:53 INFO - 2016-05-09 00:25:53.570 screenresolution[1642:7520] Display 0: 1600x1200x32@60 00:25:53 INFO - 2016-05-09 00:25:53.576 screenresolution[1643:7522] starting screenresolution argv=screenresolution list 00:25:53 INFO - Available Modes on Display 0 00:25:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:25:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:25:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:25:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:25:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:25:53 INFO - Intel Iris: 00:25:53 INFO - Chipset Model: Intel Iris 00:25:53 INFO - Type: GPU 00:25:53 INFO - Bus: Built-In 00:25:53 INFO - VRAM (Dynamic, Max): 1536 MB 00:25:53 INFO - Vendor: Intel (0x8086) 00:25:53 INFO - Device ID: 0x0a2e 00:25:53 INFO - Revision ID: 0x0009 00:25:53 INFO - Displays: 00:25:53 INFO - Display: 00:25:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:25:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:25:53 INFO - Display Serial Number: 1600x1200 60 00:25:53 INFO - Main Display: Yes 00:25:53 INFO - Mirror: Off 00:25:53 INFO - Online: Yes 00:25:53 INFO - Rotation: Supported 00:25:53 INFO - Return code: 0 00:25:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:25:53 INFO - Python 2.7.3 00:25:53 INFO - Return code: 0 00:25:53 INFO - grabbing minidump binary from tooltool 00:25:53 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')]} 00:25:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce97d30>, '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 0x10ccbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:25:53 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 00:25:53 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 00:25:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:25:53 INFO - Return code: 0 00:25:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:25:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:25:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:25:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:25:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:25:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:25:55 INFO - Return code: 0 00:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 00:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 00:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.942lBzFEpv/Render', 00:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:25:55 INFO - 'HOME': '/Users/cltbld', 00:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:25:55 INFO - 'LOGNAME': 'cltbld', 00:25:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:25:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:55 INFO - 'MOZ_NO_REMOTE': '1', 00:25:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:25:55 INFO - 'NO_EM_RESTART': '1', 00:25:55 INFO - 'PAGER': '/bin/cat', 00:25:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:55 INFO - 'PWD': '/builds/slave/test', 00:25:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:25:55 INFO - 'SHELL': '/bin/bash', 00:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yZ25DLEE96/Listeners', 00:25:55 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 00:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:25:55 INFO - 'USER': 'cltbld', 00:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:55 INFO - 'XPC_FLAGS': '0x0', 00:25:55 INFO - 'XPC_SERVICE_NAME': '0', 00:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:25:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 00:25:55 INFO - mozversion application_buildid: 20160508232942 00:25:55 INFO - mozversion application_changeset: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 00:25:55 INFO - mozversion application_display_name: Nightly 00:25:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:25:55 INFO - mozversion application_name: Firefox 00:25:55 INFO - mozversion application_remotingname: firefox 00:25:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:25:55 INFO - mozversion application_vendor: Mozilla 00:25:55 INFO - mozversion application_version: 49.0a1 00:25:55 INFO - mozversion platform_buildid: 20160508232942 00:25:55 INFO - mozversion platform_changeset: 77c1d14c1e1f389a6e4dffd50e401cf739461a82 00:25:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:25:55 INFO - mozversion platform_version: 49.0a1 00:25:55 INFO - using testdate: 1462778755 00:25:55 INFO - actual date: 1462778755 00:25:55 INFO - starting webserver on 'localhost:49277' 00:25:55 INFO - SUITE-START | Running 2 tests 00:25:55 INFO - TEST-START | dromaeo_css 00:25:55 INFO - operating with platform_type : mac_ 00:25:55 INFO - Initialising browser for dromaeo_css test... 00:25:55 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpmjMWmn/profile http://localhost:49277/getInfo.html) 00:25:57 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 00:25:57 INFO - PROCESS | 1661 | colorDepth:24 00:25:57 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 00:25:57 INFO - PROCESS | 1661 | __metrics 00:25:57 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:25:57 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:25:58 INFO - TEST-INFO | 1661: exit 0 00:25:58 INFO - Browser initialized. 00:25:58 INFO - Running cycle 1/1 for dromaeo_css test... 00:25:58 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpmjMWmn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:26:10 INFO - PROCESS | 1663 | RSS: Main: 239071232 00:26:10 INFO - PROCESS | 1663 | 00:27:01 INFO - PROCESS | 1663 | 1462778821503 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] 00:29:11 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 00:29:12 INFO - PROCESS | 1663 | RSS: Main: 324808704 00:29:12 INFO - PROCESS | 1663 | 00:32:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 00:32:16 INFO - PROCESS | 1663 | RSS: Main: 369782784 00:32:16 INFO - PROCESS | 1663 | 00:35:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 00:35:19 INFO - PROCESS | 1663 | RSS: Main: 362868736 00:35:19 INFO - PROCESS | 1663 | 00:38:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 00:38:22 INFO - PROCESS | 1663 | RSS: Main: 366698496 00:38:22 INFO - PROCESS | 1663 | 00:41:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 00:41:26 INFO - PROCESS | 1663 | RSS: Main: 330842112 00:41:26 INFO - PROCESS | 1663 | 00:44:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 00:44:29 INFO - PROCESS | 1663 | RSS: Main: 378937344 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | __start_tp_report 00:44:29 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 00:44:29 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 00:44:29 INFO - PROCESS | 1663 | |i|pagename|runs| 00:44:29 INFO - PROCESS | 1663 | |0;dojo.html;2998.4752668283054;3418.8290585470736;3442.793432394055;3475.0791040374297;3498.370293347198;5624.775008999635;5655.321361436623;5657.179708942201;5734.397888221739;5737.655740655564;5627.493525582697;5687.9146812797735;5765.625234359763;5796;5837.036888913335;5126.102931986906;5135.64050516464;5188.37739471264;5210.921836172461;5320.840374788762;5286.894262114755;5328.360596728398;5351.892962140755;5359.222912677679;5364.409914909357;5138.563222126124;5173.922391164136;5215.947840521584;5228.137357336035;5251.711155886424;4832.565069143759;5171.534561889448;5173.456787037345;5203.531682148587;5301.973490132525;4304.397384366192;5182.196759502245;5196.636235463519;5219.060569097561;5222.764975576109;5487.725613719298;5571.66570005801;5660.943390566083;5667.234923285345;5674.914876276859;6046.6759202599005;6161.260648722137;6208.441240288351;6217.751289948396;6255.40573645503;6272.18461599989;6325.399087086766;6334.144890439824;6362.395572420613;6364.077208804697;5703.714814259353;5748.02283611787;5765.596408251382;5832.329282132567;5835.270591176103;6371.267304260069;6386.137871387396;6393.328700486475;6405.391487808651;6417.807465776034;6171.537134714825;6188.1027251049245;6193;6194.0708893666315;6209.565330426917;6127.128444391265;6240.157578726905;6272.153259310026;6363.96818015907;6369.20384951881;6174.783882564088;6209.130721698965;6248.937510624926;6249.968750156311;6267.310595834455;6293.276273228637;6318.873622527523;6326.588771729823;6331.493480521547;6334.683265836689;4868.099401610713;4875.390576177978;4880.731559764247;4912.459629440758;4955.950440495621;4398.1643487737865;4450.287953927421;4463.218936686067;4480.260756975063;4482.462104547475;4820;5724.79963201286;5727.140928860621;5730.799422020209;5733.025385684444;6149.446549810449;6261.217347831521;6264.310925798159;6269.934111201107;6311.621302721759;5741.138829175574;5744;5759.251297331404;5779.768809247667;5784.1902133701315;5307.6019298552765;5411.485908838654;5430.294061771944;5434.076207044812;5442.428545002796;6250.0937364082865;6272.717727702265;6323.9683801580695;6377.74489020434;6411.166548348778;6289.779857704958;6295.055741638791;6316;6337.334579869047;6338.809835704937;6212.844678883064;6251.68741562911;6365.8726825464155;6368.968155159195;6384.968075159594;6136.886723215932;6209.379062093754;6248.843778905563;6280.371962803683;6282.120503129474;6290.24517057956;6303.1175635412;6303.842403939938;6308.968455157878;6312.5265605078885;5964.940350596612;6117.938820611737;6141;6154.292256390393;6179.073139029181;5959.404059594006;6124.564025909317;6127.816165515042;6168.228971378578;6172.537059720628;5897.321807992136;5961.374055724086;5976.372480889444;5980.641161530322;5988.431099045583;5930.021546444788;6034.487068599218;6058.4244496772735;6099.146119543356;6146.416090471398;4940.7035577865445;5444.891102178013;5500.779968801203;5975.611585246945;5977.432143946318 00:44:29 INFO - PROCESS | 1663 | |1;ext.html;511.76970363336505;519.1226826662937;528.7620570743165;576.1242910775621;596.448285336064;26878;31202.375952480936;41084.58915410841;41927.371089433684;42053.36919946203;42535.936601584974;42953;43100.568994310044;43103.35344969828;43122;8637.093105223956;9623.566939487722;9819.214462842956;9819.410835349867;9831.508424578778;8736.825263494726;11526.19316647835;11772.470238839272;11773.411329433537;11848.407579621027;11796.823047654292;11841.230320029214;11876.821847672292;11881.405929703524;11885.28688278706;8727.432716873383;11595.768084638345;11770.999465045481;11849.170558060896;11885.702857428547;11674.649760507198;11770.58802941893;11772.23480473775;11776;11794.820517948223;39051.8047409764;39427.60572394268;39429;39442;39495.60504394948;3197.4404479216278;3205.983970080135;3251.3659836331926;3269.820159891205;3410.84651190697;27648.308792280153;27974.860125699444;28267.434651307063;28486;29038.564421533694;2722.349271831363;2743.5884617307565;2756.986215068912;2770.8476033818333;2809.971900281012;462388;463729;464506;468113;468329;34579;63723;64963.67518162379;65146;65289.347106529276;35036.64963350385;36550.45174322387;36590.26819463649;36600.81699591538;36611.63388366136;15334.846651533566;15532.456364027204;15540.844591554165;15555.611109722347;15572.221388930508;11939.283642981449;12125.454354554066;12126.151169418232;12322.445489952794;13007;7696.538207707556;8000.759977200693;8143.674253029931;8153.429259951865;8292.212239837207;3104.4412005839167;3343.6823501767294;3382.8139452330356;3403.302323023774;3421.0763093964492;865.8701194820827;979.7648564344648;1017.5675337981328;1076.2143635146388;1125.6623013096203;28709.712902870702;29104.7089529102;29506.704932950397;29893.850530747208;29912.40175196484;970.3304719743354;1147.2542847149252;1159.976800463986;1179.2511755035614;1242.6582689760207;12101.515939362504;12210.57262995791;14649.633759156106;15018.324175412132;15455.30451129702;617216;620197;625728;626983;628838;57049.71475142598;57486.71256643774;57525.849483010104;57548.42451575514;57590.7120464395;1305.9673508162182;1448.5871526615074;1464.6777708904024;1480.6076389756938;1502.1963249661378;29920.952766653063;29991.70008299889;30520;30611.928582499506;31206;57045.42954570401;57691.423085768605;57861.4213857856;57889;58020.12969805541;543126;561851;564642;565522;567462;34556;40875;40915.59084409237;41754.791226044894;41787.58212417837;44671.10657786761;44862.55137448584;44972.55027449683;45542.31686524771;46135;36175.095622609646;37495.43756843595;37514;37598.43602346021;38370.42444363393;30555.2361190972;36139.27721445609;37219.62780372162;37547.62452375441;37870.24259514739;36109.81945090258;36984.44523332206;37079.443808343436;37181;37621.871343859726;32176.873809416556;32776;33071.51963602691;33072.834635826664;33247.83632572848;22142.55714885661;24382;24429;24918.626220606344;25384.238472845846 00:44:29 INFO - PROCESS | 1663 | |2;jquery.html;308.57570840094877;353.93452211340906;391.9980400097999;392.0805710608624;406.7417190084299;20292.797072029276;20821.375358739224;21181.576368472623;22141.446463838412;22187.44531386716;11839.408029598508;12058;12281;12288.815667764991;12382.19515731479;5519.31008623923;5712.542996560286;5726.770929162829;5730.971345143289;5733.48398644122;3964.167524819777;4234.470691163605;5937.554683398741;6226.844328891769;6246.531510136735;5718.571107166979;6254.655993920333;6257.906131408033;6264.718087686065;6278.37216278373;5825.883482330372;5972.492338151241;6082.391760823926;6089.634621922654;6096.695165241742;5971.970140149315;5999.490043346321;6014.729337179793;6016.4885984691355;6025.126356678301;13364.198148111145;13713.725725485434;13959.86040139606;13960.022798404116;13980.300984950713;2049.2212959075455;2166.5991791518463;2176.2927048709075;2236.7092278003756;2316.7219933607907;10147.64483243083;10238.334508257014;12443;13872.861271387257;14257.429702811874;1433.4767809749471;1598.6003499125218;1624.553247856837;1634.1747417554177;1648.7609296651917;113838.86161138366;118085.40957295244;119735.60528789462;119896.40051799685;121368;9653.372530785477;10232.744181395376;10400.843987340197;10437.68686939393;10617.73455663599;12731.80902286448;12737.363131843556;12823.487060517662;12842.807357889638;12853.293068881114;8211.671533138728;8381.664733410717;8399.70601028961;8506.702265420805;8521.10528394522;7125.216226215112;8774.692885748967;8792.912070879209;8793.977385201439;8957.372983891066;4014.437978682987;4084.7140700150703;4091.1204091120553;4137.937930931039;4156.189543039139;1513.3870782333172;1814.4695087451828;1835.5671054403465;1964.528513156832;2014.0735261779453;856.8629019356873;906.9410488318238;938.8967213606497;987.160913223754;1002.4837208837455;16318.673626527403;16865.91567042157;19997.700034499496;20473.283435079702;20966.790332096483;888.4669198480989;960.2174228004154;961.9230461563059;966.2221911361421;982.2878413150409;10508.474576271308;10916.454177291103;10948.95984881435;10957.397343146204;10973.3964631946;604364;608248;621220;644549.327040379;650318;116106.25840612398;128855;132947;135693.3215333917;143568;994.6319861651235;1023.9641612543526;1030.3251370352432;1032.1794173631831;1035.6012935020071;15160.393584256506;17803.287868485117;18159.36442224516;18689.252429903194;18931.33740319082;53636;53636.46363536315;54336;54631.180532291255;54734.17898731442;78635.5957467961;78737;79040.60479697565;79483.2051679499;80192.19807801847;7062.71749130029;7585;8026.678932842621;8077.7576672699915;8538.274246689101;7765.844683106193;8909.821803563762;12678.302693351954;12791.42438590247;13020.088593798344;10960.671179864617;11177.217594768614;11500.884991149982;11527.88472115268;11555.88444115548;8639.740807775777;9764.511774411392;10721.731956701145;11166.944165279121;11410.714732131763;11167.274127181707;11315.603953861575;11557.42212889369;11806.527738890692;11810;5895.852603684943;7533.8116547086765;8222.83554328922;8333.333333333198;8607.569621518775;10891.346519208873;11148.331100134017;11255.380954047607;11518;11609.6517104487 00:44:29 INFO - PROCESS | 1663 | |3;mootools.html;1595.2821230446302;1618.76527903454;1832.8808627439216;1953.9902300488486;1960.7745109312416;8536.871946920794;8842.381033327669;8990.460572365655;8990.640374385033;9009.32430067745;7811.101723301822;8100.189981001897;8467.449615774985;8708.085651006648;8708.651653933835;2452.9754702453015;2502.224310463762;2504.273760609421;2505.6366826810104;2506.9972011195578;2173.5544213436206;2254.627986382245;2433.561958847407;2512.886920088591;2514.421683012908;2386.331827088409;2460.1266550374626;2462.212092130519;2470.641756945242;2480.8139389545768;2419.794317483016;2480.9255722328357;2489.0914816092177;2489.116363690882;2490.252924122774;2443.8289319747623;2459.4343301040863;2468.691413573303;2472.5425796227755;2473.13440295895;8774.912250877473;8831.867521987175;8846.46921184731;8860.911390886138;8874.645014199425;5229.581633469352;5399.028174928511;5427.565794736412;5427.62006659534;5436.619436639437;3428.2629234714655;3528.788272703646;3622.2574372253625;3622.4747411625167;3624.9093772655633;952.2810278239954;969.9427623889994;1031.4533297352416;1031.9019693129142;1031.9019693129142;11678.883211167864;11694.2983420995;11703.53185872564;11710.882891171064;11711.88288117125;4764.118638051902;4799.138309330957;4871.196252618278;4891.853244402602;4906.386701662293;8478.448900821428;9915.900840991499;9916.553755081039;9926.15627671656;9943.552540135712;5345.38528069277;5415.810446634349;5427.104527752956;5429.864253393618;5439.374471935778;4843.104025755176;4857.53853383928;4862.927056094161;4961.578265847371;4969.105560998983;3979.144483935968;3983.92032159361;4039.4546736190828;4079.73481723687;4093.4473846030405;1227.846519185102;1267.702090008847;1268.2390565660714;1268.9746205075846;1316.947322107105;787.1223585701902;847.6228078505129;933.5472295936465;945.2485274207022;953.828310904044;5189.714565698847;5882.852928676817;5941.792037278675;6041.909371359433;6184.721687524072;833.2375876073326;960.1023043454393;974.4153507895348;979.715882394112;1010.4594042187395;3891.20230352777;3977.840886364571;3996.960030399659;4041.474608300902;4063.756174629532;10377.325473844327;10667.359958402521;10875.78248435027;10886.891131088587;10891.400972946563;10707.518161682743;10840.512176952056;10843.240973131806;10844.186685998582;10849.67450976472;8112.959435202786;8294.917050829536;8300.626471808782;8307.169283071644;8318.584070796556;5914.260717410324;5952;6074.574779765374;6112.816615501542;6192.188592548161;9389.389689670423;9939.751506212402;10058.8491172631;10065.496725163859;10067.748306292402;9803.558839852081;10088.19294456427;10147.847782283125;10185.185185185315;10258.28192026551;7395.630218489162;7418.554886706815;7530.28462296081;7555.09338879338;7561.6597253122645;8108.310793582612;8121.35029197651;8198.50808951441;8203.220694034057;8203.876941845758;7230.674619642024;7408.518446300973;7498.302598387206;7518.736844210426;7589.734359297398;7230.168530619047;7232.855342893218;7289.708411663474;7372.594507301936;7421.332080112816;7016.614086225102;7036.155661320674;7058.082449281561;7058.470614704021;7067.611281379368;4217.388478670514;4758.643101767312;4961.42943561495;4996.550310472075;5029.84910452687;4961.982007966587;4982.800687972585;5026.145555255543;5082.186850104114;5086.720230387365 00:44:29 INFO - PROCESS | 1663 | |4;prototype.html;286.16582661541605;300.8691219319596;305.267358339984;315.22926444842363;323.6520740204281;4810.687305325156;5051.242313652954;5349.5720342372615;5356.758945847437;5388.541973932211;4244.172386384656;5099.974500127494;5143.3570803649545;5223.477652234775;5257.6330154159905;3020.894268700596;3944.4872166618443;3948.3287841066945;3983.741056831311;4071.8982025449304;3245.75656825739;3720.5535335759746;3838.692904567642;3859.7298189126773;4046.5548789633117;3802.7338086333966;4005.7596544207295;4038.5759495253014;4068.6338229559333;4071.6335529802313;4054.2702313583545;4057.736247143941;4068.294584101569;4081.244969680589;4085.285075111873;3410.488426735985;3457.299183252725;3507.438809790421;3511;3514.2620049789575;4494.741472387736;4741.885656870632;5043.209660570481;5062.341895553592;5084.466131056222;4382.802773875152;4585.633149348044;4585.679002469828;4593;4623.606993405565;4770.878843471782;5177;5543;5609.859753506154;5622.522085622727;3855.363864962278;3862.517185351831;3869.3808990561374;3874.3219936511277;3880.5149356330458;5267.947320526784;5618.4662457066515;5861.120831875211;5908.231929849135;5910.290765108214;3272.985374533902;3618.4029635109914;3660.9450858237146;3929.5677475477673;3937.9606203938165;3532.1346270163463;3649.39784935488;3702.33357995558;3989.4215338776294;3995.161016186575;4064.878053658395;4291.592298731678;4431.955680443154;4451.666125040635;4459.977700111479;4737.8341758038305;4796.880077998008;5545.916811247835;5573.944260557424;5595.552355811526;1145.9457299284677;1253.7053792358788;1269.803180507023;1275.8405199350082;1276.6021206778562;828.9088200297963;841.6071401829981;867.5705525764787;871.7210492642421;881.4931414436596;4325.275076701658;4477.3731677565165;4805.038992201574;4884.8290309838985;4888.846693882426;4671.532846715301;4781.282807578821;4844.394450693663;5316.93661267748;5508.394076651565;4464.53122422141;4496.797644106023;4798.7840547175465;4953.678010929278;4955.306257124005;2602.53154432204;2626.540355437791;2690.556058250406;2728.399752054545;2735.2478068530913;5233.528982391603;5249.086756849985;5255.5795536357;5624.775008999594;5699.202111704365;4999.675151054777;5324.41431442541;5645.2096706461125;5653.519450846642;5776.537876969833;4997.900041999213;5284.504115719762;5772.740226689809;5799.971000144972;5815.244018277682;4801.327814106098;4876.878078048077;5239.502247286654;5416.512513873613;5475.972620136874;5275.366955965309;5647.745851436777;5766.4233576642;5790.623609465371;5802.65184088956;5256.395514515727;6006.309274433322;6034.7284372202475;6052.334243233329;6078.939210607838;3369.3935091683734;3771.2268984857487;3895.889671443625;3899.68802495794;3911.5697273299347;5485.9725701371235;5627.0434026214825;5651.971740141273;5795.33353664334;5854.443827836349;5159.845204643867;5215.843524694265;5611.214429979887;5643.673736671961;5656.925184214986;4723.574878260973;5497.972510137423;5597.524210442158;5607;5613.494785469327;4958.107540642719;5465.480779325958;5466.316710411199;5485.835424937259;5536.501714845684;4732.597729193058;5072.289879416959;5337.452138879681;5600.395815945605;5613.326400831763;4937.876553086202;5145.45972672864;5284.9735751320995;5439.211314359475;5521.3926468087675 00:44:29 INFO - PROCESS | 1663 | |5;yui.html;661.9238787539433;729.6351824087952;750.0998801438276;753.2053683364052;779.8105747492754;14579.489717859877;15144.469943551978;15177.937544371898;15185.544433666993;15206.695866082671;13699.931500342509;13997.790033149486;14058.226797526131;14058.859411405883;14069.296535173251;858.159004175908;877.4910551879894;888.7778055486128;909.8498747706637;932.0772435289049;892.6117139044536;899.6221586933503;905.9320550958671;906.5920335848862;908.7001289574424;958.3483231402644;958.8513780364054;959.3859929645033;959.9808003839919;961.0437614573509;948.9952550237274;949.1599934058339;951.8524628682566;952.1288021460356;952.9332946693734;709.1915216652968;713.0730050933818;713.4043074033189;714.5105602662193;715.2525610736792;12901.483940642363;12931.224126552437;12940;12941.352932353439;12954.546590869368;10846.72883177919;11062.944685276521;11146.777064458665;11215.214934954556;11249;9280.675176368863;9400.747198007472;9429.669961551313;9694.612215511372;10177.491125443772;3402.761806673534;3405.404054290489;3413.7098346640564;3426.1777173478436;3453.533772940671;71750.64124679395;71798.28201717968;72152.63923680349;74835.87746183811;74946.87579686363;6859.3140685932;8096.271335579826;8163.918360816435;8170.550619715973;8211.260986511243;19058;19063.714044289347;19090.14094365757;19114.617707645768;19116.61766764657;7245.021922040457;7248.710051597983;7281.817954551178;7465.962670186723;7494.6627401767055;3867.2458870519977;3955.723099383016;3970.3845903884944;3982.900427489278;3999.5400528939545;3064.387122575449;3523.5419395478425;3635.818209089535;3657.140571965586;3681.7054635629083;527.5041461026623;634.1660716158237;639.309545690653;643.2827397451902;645.7998020613625;557.6654007595491;561.9269494965629;581.383733242764;589.8495883549667;606.1483615520114;8019.238172373615;8100.797480062928;8154.103048664642;8174.591270436574;8293.95853020743;498.60111910471477;528.8022628745894;592.0940960330701;594.3759052994338;608.9391060893964;3743.3636281832155;3835.2521258354354;3876.9418458723144;3946.427767973685;3953.011747063234;81420;81482;82503.5874820634;83287.5835620818;83441;71255;71641.92537111948;72560;72936.63531682307;72995.63502182561;64513.03230451547;64659;64731;64851.67574162099;65419;7497.437692173218;7553.848923021619;7601.885971710318;7747.612619368896;8006.1993800619475;40837.795811020755;41179.38230926479;41272.968175795846;41812.581874182084;42241;53883.73058134684;56629.71685141548;58254.2523724289;58489.348607278014;58890;8616.913830861862;10068.613291888805;10255.384676919408;10435.739106522095;10935.343879367263;10865.076468500267;10945.890541094486;11094.278871873303;11147.665570032912;11491.48288327044;10033.949830250802;10054.989002199736;10399.32404393712;10659.626913058006;10705.518251678848;9734.9026509734;9983.60065597397;10286.177105831664;10361;10408.739781505523;10243.282970192015;10459.738506537398;10559.260851740304;10563.419011954416;10607.310524815863;8641.697540586048;8859.778505537413;8879.600417981077;8968.551572421484;9179.31155163352;8855.3801233913;8914.821703565764;8934.329925255503;8945.59744811498;8991.28069754405 00:44:29 INFO - PROCESS | 1663 | __end_tp_report 00:44:29 INFO - PROCESS | 1663 | __start_cc_report 00:44:29 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1216 00:44:29 INFO - PROCESS | 1663 | __end_cc_report 00:44:29 INFO - PROCESS | 1663 | __startTimestamp1462779869192__endTimestamp 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | ------- Summary: start ------- 00:44:29 INFO - PROCESS | 1663 | Number of tests: 6 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5745.99 Median:5964.94 stddev:668.35 (11.2%) stddev-sans-first:634.70 00:44:29 INFO - PROCESS | 1663 | Values: 2998.5 3418.8 3442.8 3475.1 3498.4 5624.8 5655.3 5657.2 5734.4 5737.7 5627.5 5687.9 5765.6 5796.0 5837.0 5126.1 5135.6 5188.4 5210.9 5320.8 5286.9 5328.4 5351.9 5359.2 5364.4 5138.6 5173.9 5215.9 5228.1 5251.7 4832.6 5171.5 5173.5 5203.5 5302.0 4304.4 5182.2 5196.6 5219.1 5222.8 5487.7 5571.7 5660.9 5667.2 5674.9 6046.7 6161.3 6208.4 6217.8 6255.4 6272.2 6325.4 6334.1 6362.4 6364.1 5703.7 5748.0 5765.6 5832.3 5835.3 6371.3 6386.1 6393.3 6405.4 6417.8 6171.5 6188.1 6193.0 6194.1 6209.6 6127.1 6240.2 6272.2 6364.0 6369.2 6174.8 6209.1 6248.9 6250.0 6267.3 6293.3 6318.9 6326.6 6331.5 6334.7 4868.1 4875.4 4880.7 4912.5 4956.0 4398.2 4450.3 4463.2 4480.3 4482.5 4820.0 5724.8 5727.1 5730.8 5733.0 6149.4 6261.2 6264.3 6269.9 6311.6 5741.1 5744.0 5759.3 5779.8 5784.2 5307.6 5411.5 5430.3 5434.1 5442.4 6250.1 6272.7 6324.0 6377.7 6411.2 6289.8 6295.1 6316.0 6337.3 6338.8 6212.8 6251.7 6365.9 6369.0 6385.0 6136.9 6209.4 6248.8 6280.4 6282.1 6290.2 6303.1 6303.8 6309.0 6312.5 5964.9 6117.9 6141.0 6154.3 6179.1 5959.4 6124.6 6127.8 6168.2 6172.5 5897.3 5961.4 5976.4 5980.6 5988.4 5930.0 6034.5 6058.4 6099.1 6146.4 4940.7 5444.9 5500.8 5975.6 5977.4 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:67731.80 Median:35573.23 stddev:148028.73 (416.1%) stddev-sans-first:148357.96 00:44:29 INFO - PROCESS | 1663 | Values: 511.8 519.1 528.8 576.1 596.4 26878.0 31202.4 41084.6 41927.4 42053.4 42535.9 42953.0 43100.6 43103.4 43122.0 8637.1 9623.6 9819.2 9819.4 9831.5 8736.8 11526.2 11772.5 11773.4 11848.4 11796.8 11841.2 11876.8 11881.4 11885.3 8727.4 11595.8 11771.0 11849.2 11885.7 11674.6 11770.6 11772.2 11776.0 11794.8 39051.8 39427.6 39429.0 39442.0 39495.6 3197.4 3206.0 3251.4 3269.8 3410.8 27648.3 27974.9 28267.4 28486.0 29038.6 2722.3 2743.6 2757.0 2770.8 2810.0 462388.0 463729.0 464506.0 468113.0 468329.0 34579.0 63723.0 64963.7 65146.0 65289.3 35036.6 36550.5 36590.3 36600.8 36611.6 15334.8 15532.5 15540.8 15555.6 15572.2 11939.3 12125.5 12126.2 12322.4 13007.0 7696.5 8000.8 8143.7 8153.4 8292.2 3104.4 3343.7 3382.8 3403.3 3421.1 865.9 979.8 1017.6 1076.2 1125.7 28709.7 29104.7 29506.7 29893.9 29912.4 970.3 1147.3 1160.0 1179.3 1242.7 12101.5 12210.6 14649.6 15018.3 15455.3 617216.0 620197.0 625728.0 626983.0 628838.0 57049.7 57486.7 57525.8 57548.4 57590.7 1306.0 1448.6 1464.7 1480.6 1502.2 29921.0 29991.7 30520.0 30611.9 31206.0 57045.4 57691.4 57861.4 57889.0 58020.1 543126.0 561851.0 564642.0 565522.0 567462.0 34556.0 40875.0 40915.6 41754.8 41787.6 44671.1 44862.6 44972.6 45542.3 46135.0 36175.1 37495.4 37514.0 37598.4 38370.4 30555.2 36139.3 37219.6 37547.6 37870.2 36109.8 36984.4 37079.4 37181.0 37621.9 32176.9 32776.0 33071.5 33072.8 33247.8 22142.6 24382.0 24429.0 24918.6 25384.2 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35261.80 Median:12158.87 stddev:104491.15 (859.4%) stddev-sans-first:104751.31 00:44:29 INFO - PROCESS | 1663 | Values: 308.6 353.9 392.0 392.1 406.7 20292.8 20821.4 21181.6 22141.4 22187.4 11839.4 12058.0 12281.0 12288.8 12382.2 5519.3 5712.5 5726.8 5731.0 5733.5 3964.2 4234.5 5937.6 6226.8 6246.5 5718.6 6254.7 6257.9 6264.7 6278.4 5825.9 5972.5 6082.4 6089.6 6096.7 5972.0 5999.5 6014.7 6016.5 6025.1 13364.2 13713.7 13959.9 13960.0 13980.3 2049.2 2166.6 2176.3 2236.7 2316.7 10147.6 10238.3 12443.0 13872.9 14257.4 1433.5 1598.6 1624.6 1634.2 1648.8 113838.9 118085.4 119735.6 119896.4 121368.0 9653.4 10232.7 10400.8 10437.7 10617.7 12731.8 12737.4 12823.5 12842.8 12853.3 8211.7 8381.7 8399.7 8506.7 8521.1 7125.2 8774.7 8792.9 8794.0 8957.4 4014.4 4084.7 4091.1 4137.9 4156.2 1513.4 1814.5 1835.6 1964.5 2014.1 856.9 906.9 938.9 987.2 1002.5 16318.7 16865.9 19997.7 20473.3 20966.8 888.5 960.2 961.9 966.2 982.3 10508.5 10916.5 10949.0 10957.4 10973.4 604364.0 608248.0 621220.0 644549.3 650318.0 116106.3 128855.0 132947.0 135693.3 143568.0 994.6 1024.0 1030.3 1032.2 1035.6 15160.4 17803.3 18159.4 18689.3 18931.3 53636.0 53636.5 54336.0 54631.2 54734.2 78635.6 78737.0 79040.6 79483.2 80192.2 7062.7 7585.0 8026.7 8077.8 8538.3 7765.8 8909.8 12678.3 12791.4 13020.1 10960.7 11177.2 11500.9 11527.9 11555.9 8639.7 9764.5 10721.7 11166.9 11410.7 11167.3 11315.6 11557.4 11806.5 11810.0 5895.9 7533.8 8222.8 8333.3 8607.6 10891.3 11148.3 11255.4 11518.0 11609.7 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5742.30 Median:4989.68 stddev:3170.59 (63.5%) stddev-sans-first:3164.17 00:44:29 INFO - PROCESS | 1663 | Values: 1595.3 1618.8 1832.9 1954.0 1960.8 8536.9 8842.4 8990.5 8990.6 9009.3 7811.1 8100.2 8467.4 8708.1 8708.7 2453.0 2502.2 2504.3 2505.6 2507.0 2173.6 2254.6 2433.6 2512.9 2514.4 2386.3 2460.1 2462.2 2470.6 2480.8 2419.8 2480.9 2489.1 2489.1 2490.3 2443.8 2459.4 2468.7 2472.5 2473.1 8774.9 8831.9 8846.5 8860.9 8874.6 5229.6 5399.0 5427.6 5427.6 5436.6 3428.3 3528.8 3622.3 3622.5 3624.9 952.3 969.9 1031.5 1031.9 1031.9 11678.9 11694.3 11703.5 11710.9 11711.9 4764.1 4799.1 4871.2 4891.9 4906.4 8478.4 9915.9 9916.6 9926.2 9943.6 5345.4 5415.8 5427.1 5429.9 5439.4 4843.1 4857.5 4862.9 4961.6 4969.1 3979.1 3983.9 4039.5 4079.7 4093.4 1227.8 1267.7 1268.2 1269.0 1316.9 787.1 847.6 933.5 945.2 953.8 5189.7 5882.9 5941.8 6041.9 6184.7 833.2 960.1 974.4 979.7 1010.5 3891.2 3977.8 3997.0 4041.5 4063.8 10377.3 10667.4 10875.8 10886.9 10891.4 10707.5 10840.5 10843.2 10844.2 10849.7 8113.0 8294.9 8300.6 8307.2 8318.6 5914.3 5952.0 6074.6 6112.8 6192.2 9389.4 9939.8 10058.8 10065.5 10067.7 9803.6 10088.2 10147.8 10185.2 10258.3 7395.6 7418.6 7530.3 7555.1 7561.7 8108.3 8121.4 8198.5 8203.2 8203.9 7230.7 7408.5 7498.3 7518.7 7589.7 7230.2 7232.9 7289.7 7372.6 7421.3 7016.6 7036.2 7058.1 7058.5 7067.6 4217.4 4758.6 4961.4 4996.6 5029.8 4962.0 4982.8 5026.1 5082.2 5086.7 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4432.49 Median:4886.84 stddev:1367.80 (28.0%) stddev-sans-first:1335.77 00:44:29 INFO - PROCESS | 1663 | Values: 286.2 300.9 305.3 315.2 323.7 4810.7 5051.2 5349.6 5356.8 5388.5 4244.2 5100.0 5143.4 5223.5 5257.6 3020.9 3944.5 3948.3 3983.7 4071.9 3245.8 3720.6 3838.7 3859.7 4046.6 3802.7 4005.8 4038.6 4068.6 4071.6 4054.3 4057.7 4068.3 4081.2 4085.3 3410.5 3457.3 3507.4 3511.0 3514.3 4494.7 4741.9 5043.2 5062.3 5084.5 4382.8 4585.6 4585.7 4593.0 4623.6 4770.9 5177.0 5543.0 5609.9 5622.5 3855.4 3862.5 3869.4 3874.3 3880.5 5267.9 5618.5 5861.1 5908.2 5910.3 3273.0 3618.4 3660.9 3929.6 3938.0 3532.1 3649.4 3702.3 3989.4 3995.2 4064.9 4291.6 4432.0 4451.7 4460.0 4737.8 4796.9 5545.9 5573.9 5595.6 1145.9 1253.7 1269.8 1275.8 1276.6 828.9 841.6 867.6 871.7 881.5 4325.3 4477.4 4805.0 4884.8 4888.8 4671.5 4781.3 4844.4 5316.9 5508.4 4464.5 4496.8 4798.8 4953.7 4955.3 2602.5 2626.5 2690.6 2728.4 2735.2 5233.5 5249.1 5255.6 5624.8 5699.2 4999.7 5324.4 5645.2 5653.5 5776.5 4997.9 5284.5 5772.7 5800.0 5815.2 4801.3 4876.9 5239.5 5416.5 5476.0 5275.4 5647.7 5766.4 5790.6 5802.7 5256.4 6006.3 6034.7 6052.3 6078.9 3369.4 3771.2 3895.9 3899.7 3911.6 5486.0 5627.0 5652.0 5795.3 5854.4 5159.8 5215.8 5611.2 5643.7 5656.9 4723.6 5498.0 5597.5 5607.0 5613.5 4958.1 5465.5 5466.3 5485.8 5536.5 4732.6 5072.3 5337.5 5600.4 5613.3 4937.9 5145.5 5285.0 5439.2 5521.4 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:16551.77 Median:641.30 stddev:22965.42 (3581.1%) stddev-sans-first:22998.85 00:44:29 INFO - PROCESS | 1663 | Values: 661.9 729.6 750.1 753.2 779.8 14579.5 15144.5 15177.9 15185.5 15206.7 13699.9 13997.8 14058.2 14058.9 14069.3 858.2 877.5 888.8 909.8 932.1 892.6 899.6 905.9 906.6 908.7 958.3 958.9 959.4 960.0 961.0 949.0 949.2 951.9 952.1 952.9 709.2 713.1 713.4 714.5 715.3 12901.5 12931.2 12940.0 12941.4 12954.5 10846.7 11062.9 11146.8 11215.2 11249.0 9280.7 9400.7 9429.7 9694.6 10177.5 3402.8 3405.4 3413.7 3426.2 3453.5 71750.6 71798.3 72152.6 74835.9 74946.9 6859.3 8096.3 8163.9 8170.6 8211.3 19058.0 19063.7 19090.1 19114.6 19116.6 7245.0 7248.7 7281.8 7466.0 7494.7 3867.2 3955.7 3970.4 3982.9 3999.5 3064.4 3523.5 3635.8 3657.1 3681.7 527.5 634.2 639.3 643.3 645.8 557.7 561.9 581.4 589.8 606.1 8019.2 8100.8 8154.1 8174.6 8294.0 498.6 528.8 592.1 594.4 608.9 3743.4 3835.3 3876.9 3946.4 3953.0 81420.0 81482.0 82503.6 83287.6 83441.0 71255.0 71641.9 72560.0 72936.6 72995.6 64513.0 64659.0 64731.0 64851.7 65419.0 7497.4 7553.8 7601.9 7747.6 8006.2 40837.8 41179.4 41273.0 41812.6 42241.0 53883.7 56629.7 58254.3 58489.3 58890.0 8616.9 10068.6 10255.4 10435.7 10935.3 10865.1 10945.9 11094.3 11147.7 11491.5 10033.9 10055.0 10399.3 10659.6 10705.5 9734.9 9983.6 10286.2 10361.0 10408.7 10243.3 10459.7 10559.3 10563.4 10607.3 8641.7 8859.8 8879.6 8968.6 9179.3 8855.4 8914.8 8934.3 8945.6 8991.3 00:44:29 INFO - PROCESS | 1663 | -------- Summary: end -------- 00:44:29 INFO - PROCESS | 1663 | 00:44:29 INFO - TEST-INFO | 1663: exit 0 00:44:29 INFO - TEST-OK | dromaeo_css | took 1114526ms 00:44:29 INFO - TEST-START | kraken 00:44:29 INFO - operating with platform_type : mac_ 00:44:29 INFO - Initialising browser for kraken test... 00:44:29 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpe0dfX1/profile http://localhost:49277/getInfo.html) 00:44:31 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 00:44:31 INFO - PROCESS | 1674 | colorDepth:24 00:44:31 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 00:44:31 INFO - PROCESS | 1674 | __metrics 00:44:32 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:44:32 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:44:32 INFO - TEST-INFO | 1674: exit 0 00:44:32 INFO - Browser initialized. 00:44:32 INFO - Running cycle 1/1 for kraken test... 00:44:32 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpe0dfX1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:44:44 INFO - PROCESS | 1676 | RSS: Main: 238944256 00:44:44 INFO - PROCESS | 1676 | 00:44:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 00:44:48 INFO - PROCESS | 1676 | RSS: Main: 286924800 00:44:48 INFO - PROCESS | 1676 | 00:44:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 00:44:51 INFO - PROCESS | 1676 | RSS: Main: 294993920 00:44:51 INFO - PROCESS | 1676 | 00:44:54 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 00:44:55 INFO - PROCESS | 1676 | RSS: Main: 384503808 00:44:55 INFO - PROCESS | 1676 | 00:44:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 00:44:58 INFO - PROCESS | 1676 | RSS: Main: 284631040 00:44:58 INFO - PROCESS | 1676 | 00:45:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:45:00 INFO - PROCESS | 1676 | RSS: Main: 288202752 00:45:00 INFO - PROCESS | 1676 | 00:45:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 00:45:04 INFO - PROCESS | 1676 | RSS: Main: 325021696 00:45:04 INFO - PROCESS | 1676 | 00:45:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 00:45:07 INFO - PROCESS | 1676 | RSS: Main: 325054464 00:45:07 INFO - PROCESS | 1676 | 00:45:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 00:45:11 INFO - PROCESS | 1676 | RSS: Main: 324030464 00:45:11 INFO - PROCESS | 1676 | 00:45:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:45:13 INFO - PROCESS | 1676 | RSS: Main: 312295424 00:45:13 INFO - PROCESS | 1676 | 00:45:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:45:15 INFO - PROCESS | 1676 | RSS: Main: 320266240 00:45:15 INFO - PROCESS | 1676 | 00:45:17 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:45:18 INFO - PROCESS | 1676 | RSS: Main: 320958464 00:45:18 INFO - PROCESS | 1676 | 00:45:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:45:22 INFO - PROCESS | 1676 | RSS: Main: 325689344 00:45:22 INFO - PROCESS | 1676 | 00:45:24 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:45:25 INFO - PROCESS | 1676 | RSS: Main: 323481600 00:45:25 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 00:45:27 INFO - PROCESS | 1676 | RSS: Main: 370380800 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | __start_tp_report 00:45:27 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 00:45:27 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 00:45:27 INFO - PROCESS | 1676 | |i|pagename|runs| 00:45:27 INFO - PROCESS | 1676 | |0;ai-astar;112;107;112;113;112;113;111;112;111;112 00:45:27 INFO - PROCESS | 1676 | |1;audio-beat-detection;154;120;173;191;119;141;138;126;202;139 00:45:27 INFO - PROCESS | 1676 | |2;audio-dft;180;186;184;182;187;182;182;185;186;185 00:45:27 INFO - PROCESS | 1676 | |3;audio-fft;78;74;59;107;73;84;79;58;111;57 00:45:27 INFO - PROCESS | 1676 | |4;audio-oscillator;95;100;101;79;79;78;102;82;79;101 00:45:27 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;84;87;84;87;84;87;83;87;83 00:45:27 INFO - PROCESS | 1676 | |6;imaging-darkroom;92;93;93;93;92;93;93;92;92;91 00:45:27 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;85;83;83;83;84;87;85;86;84 00:45:27 INFO - PROCESS | 1676 | |8;json-parse-financial;62;61;60;58;59;60;59;77;61;59 00:45:27 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;62;76;74;72;70;68;67;69;68;70 00:45:27 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;85;87;86;85;85;84;85;86;93;107 00:45:27 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;137;140;138;137;138;134;135;137;176;138 00:45:27 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;170;169;165;170;168;167;168;171;204;166 00:45:27 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;68;69;79;84;86;68;68;65;66;66 00:45:27 INFO - PROCESS | 1676 | __end_tp_report 00:45:27 INFO - PROCESS | 1676 | __start_cc_report 00:45:27 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1967 00:45:27 INFO - PROCESS | 1676 | __end_cc_report 00:45:27 INFO - PROCESS | 1676 | __startTimestamp1462779927562__endTimestamp 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | ------- Summary: start ------- 00:45:27 INFO - PROCESS | 1676 | Number of tests: 14 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.72 (1.5%) stddev-sans-first:1.81 00:45:27 INFO - PROCESS | 1676 | Values: 112.0 107.0 112.0 113.0 112.0 113.0 111.0 112.0 111.0 112.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:147.50 stddev:29.27 (19.8%) stddev-sans-first:31.02 00:45:27 INFO - PROCESS | 1676 | Values: 154.0 120.0 173.0 191.0 119.0 141.0 138.0 126.0 202.0 139.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:183.90 Median:185.00 stddev:2.28 (1.2%) stddev-sans-first:1.94 00:45:27 INFO - PROCESS | 1676 | Values: 180.0 186.0 184.0 182.0 187.0 182.0 182.0 185.0 186.0 185.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:78.00 Median:73.50 stddev:18.89 (25.7%) stddev-sans-first:20.03 00:45:27 INFO - PROCESS | 1676 | Values: 78.0 74.0 59.0 107.0 73.0 84.0 79.0 58.0 111.0 57.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:79.00 stddev:10.96 (13.9%) stddev-sans-first:11.45 00:45:27 INFO - PROCESS | 1676 | Values: 95.0 100.0 101.0 79.0 79.0 78.0 102.0 82.0 79.0 101.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:87.00 stddev:1.83 (2.1%) stddev-sans-first:1.83 00:45:27 INFO - PROCESS | 1676 | Values: 87.0 84.0 87.0 84.0 87.0 84.0 87.0 83.0 87.0 83.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 00:45:27 INFO - PROCESS | 1676 | Values: 92.0 93.0 93.0 93.0 92.0 93.0 93.0 92.0 92.0 91.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 00:45:27 INFO - PROCESS | 1676 | Values: 85.0 85.0 83.0 83.0 83.0 84.0 87.0 85.0 86.0 84.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:60.50 stddev:5.54 (9.2%) stddev-sans-first:5.88 00:45:27 INFO - PROCESS | 1676 | Values: 62.0 61.0 60.0 58.0 59.0 60.0 59.0 77.0 61.0 59.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:3.89 (5.6%) stddev-sans-first:3.00 00:45:27 INFO - PROCESS | 1676 | Values: 62.0 76.0 74.0 72.0 70.0 68.0 67.0 69.0 68.0 70.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.50 stddev:7.04 (8.2%) stddev-sans-first:7.37 00:45:27 INFO - PROCESS | 1676 | Values: 85.0 87.0 86.0 85.0 85.0 84.0 85.0 86.0 93.0 107.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:138.00 stddev:12.41 (9.0%) stddev-sans-first:13.08 00:45:27 INFO - PROCESS | 1676 | Values: 137.0 140.0 138.0 137.0 138.0 134.0 135.0 137.0 176.0 138.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:169.50 stddev:11.47 (6.8%) stddev-sans-first:12.14 00:45:27 INFO - PROCESS | 1676 | Values: 170.0 169.0 165.0 170.0 168.0 167.0 168.0 171.0 204.0 166.0 00:45:27 INFO - PROCESS | 1676 | 00:45:27 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:68.50 stddev:7.94 (11.6%) stddev-sans-first:8.29 00:45:27 INFO - PROCESS | 1676 | Values: 68.0 69.0 79.0 84.0 86.0 68.0 68.0 65.0 66.0 66.0 00:45:27 INFO - PROCESS | 1676 | -------- Summary: end -------- 00:45:27 INFO - PROCESS | 1676 | 00:45:28 INFO - TEST-INFO | 1676: exit 0 00:45:28 INFO - TEST-OK | kraken | took 58638ms 00:45:28 INFO - SUITE-END | took 1173s 00:45:28 INFO - Completed test suite (00:19:33) 00:45:28 INFO - javascript benchmark 00:45:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2998.4752668283054, 3418.8290585470736, 3442.793432394055, 3475.0791040374297, 3498.370293347198, 5624.775008999635, 5655.321361436623, 5657.179708942201, 5734.397888221739, 5737.655740655564, 5627.493525582697, 5687.9146812797735, 5765.625234359763, 5796.0, 5837.036888913335, 5126.102931986906, 5135.64050516464, 5188.37739471264, 5210.921836172461, 5320.840374788762, 5286.894262114755, 5328.360596728398, 5351.892962140755, 5359.222912677679, 5364.409914909357, 5138.563222126124, 5173.922391164136, 5215.947840521584, 5228.137357336035, 5251.711155886424, 4832.565069143759, 5171.534561889448, 5173.456787037345, 5203.531682148587, 5301.973490132525, 4304.397384366192, 5182.196759502245, 5196.636235463519, 5219.060569097561, 5222.764975576109, 5487.725613719298, 5571.66570005801, 5660.943390566083, 5667.234923285345, 5674.914876276859, 6046.6759202599005, 6161.260648722137, 6208.441240288351, 6217.751289948396, 6255.40573645503, 6272.18461599989, 6325.399087086766, 6334.144890439824, 6362.395572420613, 6364.077208804697, 5703.714814259353, 5748.02283611787, 5765.596408251382, 5832.329282132567, 5835.270591176103, 6371.267304260069, 6386.137871387396, 6393.328700486475, 6405.391487808651, 6417.807465776034, 6171.537134714825, 6188.1027251049245, 6193.0, 6194.0708893666315, 6209.565330426917, 6127.128444391265, 6240.157578726905, 6272.153259310026, 6363.96818015907, 6369.20384951881, 6174.783882564088, 6209.130721698965, 6248.937510624926, 6249.968750156311, 6267.310595834455, 6293.276273228637, 6318.873622527523, 6326.588771729823, 6331.493480521547, 6334.683265836689, 4868.099401610713, 4875.390576177978, 4880.731559764247, 4912.459629440758, 4955.950440495621, 4398.1643487737865, 4450.287953927421, 4463.218936686067, 4480.260756975063, 4482.462104547475, 4820.0, 5724.79963201286, 5727.140928860621, 5730.799422020209, 5733.025385684444, 6149.446549810449, 6261.217347831521, 6264.310925798159, 6269.934111201107, 6311.621302721759, 5741.138829175574, 5744.0, 5759.251297331404, 5779.768809247667, 5784.1902133701315, 5307.6019298552765, 5411.485908838654, 5430.294061771944, 5434.076207044812, 5442.428545002796, 6250.0937364082865, 6272.717727702265, 6323.9683801580695, 6377.74489020434, 6411.166548348778, 6289.779857704958, 6295.055741638791, 6316.0, 6337.334579869047, 6338.809835704937, 6212.844678883064, 6251.68741562911, 6365.8726825464155, 6368.968155159195, 6384.968075159594, 6136.886723215932, 6209.379062093754, 6248.843778905563, 6280.371962803683, 6282.120503129474, 6290.24517057956, 6303.1175635412, 6303.842403939938, 6308.968455157878, 6312.5265605078885, 5964.940350596612, 6117.938820611737, 6141.0, 6154.292256390393, 6179.073139029181, 5959.404059594006, 6124.564025909317, 6127.816165515042, 6168.228971378578, 6172.537059720628, 5897.321807992136, 5961.374055724086, 5976.372480889444, 5980.641161530322, 5988.431099045583, 5930.021546444788, 6034.487068599218, 6058.4244496772735, 6099.146119543356, 6146.416090471398, 4940.7035577865445, 5444.891102178013, 5500.779968801203, 5975.611585246945, 5977.432143946318], "value": 5702.20327421106, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.76970363336505, 519.1226826662937, 528.7620570743165, 576.1242910775621, 596.448285336064, 26878.0, 31202.375952480936, 41084.58915410841, 41927.371089433684, 42053.36919946203, 42535.936601584974, 42953.0, 43100.568994310044, 43103.35344969828, 43122.0, 8637.093105223956, 9623.566939487722, 9819.214462842956, 9819.410835349867, 9831.508424578778, 8736.825263494726, 11526.19316647835, 11772.470238839272, 11773.411329433537, 11848.407579621027, 11796.823047654292, 11841.230320029214, 11876.821847672292, 11881.405929703524, 11885.28688278706, 8727.432716873383, 11595.768084638345, 11770.999465045481, 11849.170558060896, 11885.702857428547, 11674.649760507198, 11770.58802941893, 11772.23480473775, 11776.0, 11794.820517948223, 39051.8047409764, 39427.60572394268, 39429.0, 39442.0, 39495.60504394948, 3197.4404479216278, 3205.983970080135, 3251.3659836331926, 3269.820159891205, 3410.84651190697, 27648.308792280153, 27974.860125699444, 28267.434651307063, 28486.0, 29038.564421533694, 2722.349271831363, 2743.5884617307565, 2756.986215068912, 2770.8476033818333, 2809.971900281012, 462388.0, 463729.0, 464506.0, 468113.0, 468329.0, 34579.0, 63723.0, 64963.67518162379, 65146.0, 65289.347106529276, 35036.64963350385, 36550.45174322387, 36590.26819463649, 36600.81699591538, 36611.63388366136, 15334.846651533566, 15532.456364027204, 15540.844591554165, 15555.611109722347, 15572.221388930508, 11939.283642981449, 12125.454354554066, 12126.151169418232, 12322.445489952794, 13007.0, 7696.538207707556, 8000.759977200693, 8143.674253029931, 8153.429259951865, 8292.212239837207, 3104.4412005839167, 3343.6823501767294, 3382.8139452330356, 3403.302323023774, 3421.0763093964492, 865.8701194820827, 979.7648564344648, 1017.5675337981328, 1076.2143635146388, 1125.6623013096203, 28709.712902870702, 29104.7089529102, 29506.704932950397, 29893.850530747208, 29912.40175196484, 970.3304719743354, 1147.2542847149252, 1159.976800463986, 1179.2511755035614, 1242.6582689760207, 12101.515939362504, 12210.57262995791, 14649.633759156106, 15018.324175412132, 15455.30451129702, 617216.0, 620197.0, 625728.0, 626983.0, 628838.0, 57049.71475142598, 57486.71256643774, 57525.849483010104, 57548.42451575514, 57590.7120464395, 1305.9673508162182, 1448.5871526615074, 1464.6777708904024, 1480.6076389756938, 1502.1963249661378, 29920.952766653063, 29991.70008299889, 30520.0, 30611.928582499506, 31206.0, 57045.42954570401, 57691.423085768605, 57861.4213857856, 57889.0, 58020.12969805541, 543126.0, 561851.0, 564642.0, 565522.0, 567462.0, 34556.0, 40875.0, 40915.59084409237, 41754.791226044894, 41787.58212417837, 44671.10657786761, 44862.55137448584, 44972.55027449683, 45542.31686524771, 46135.0, 36175.095622609646, 37495.43756843595, 37514.0, 37598.43602346021, 38370.42444363393, 30555.2361190972, 36139.27721445609, 37219.62780372162, 37547.62452375441, 37870.24259514739, 36109.81945090258, 36984.44523332206, 37079.443808343436, 37181.0, 37621.871343859726, 32176.873809416556, 32776.0, 33071.51963602691, 33072.834635826664, 33247.83632572848, 22142.55714885661, 24382.0, 24429.0, 24918.626220606344, 25384.238472845846], "value": 19075.975323088092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.57570840094877, 353.93452211340906, 391.9980400097999, 392.0805710608624, 406.7417190084299, 20292.797072029276, 20821.375358739224, 21181.576368472623, 22141.446463838412, 22187.44531386716, 11839.408029598508, 12058.0, 12281.0, 12288.815667764991, 12382.19515731479, 5519.31008623923, 5712.542996560286, 5726.770929162829, 5730.971345143289, 5733.48398644122, 3964.167524819777, 4234.470691163605, 5937.554683398741, 6226.844328891769, 6246.531510136735, 5718.571107166979, 6254.655993920333, 6257.906131408033, 6264.718087686065, 6278.37216278373, 5825.883482330372, 5972.492338151241, 6082.391760823926, 6089.634621922654, 6096.695165241742, 5971.970140149315, 5999.490043346321, 6014.729337179793, 6016.4885984691355, 6025.126356678301, 13364.198148111145, 13713.725725485434, 13959.86040139606, 13960.022798404116, 13980.300984950713, 2049.2212959075455, 2166.5991791518463, 2176.2927048709075, 2236.7092278003756, 2316.7219933607907, 10147.64483243083, 10238.334508257014, 12443.0, 13872.861271387257, 14257.429702811874, 1433.4767809749471, 1598.6003499125218, 1624.553247856837, 1634.1747417554177, 1648.7609296651917, 113838.86161138366, 118085.40957295244, 119735.60528789462, 119896.40051799685, 121368.0, 9653.372530785477, 10232.744181395376, 10400.843987340197, 10437.68686939393, 10617.73455663599, 12731.80902286448, 12737.363131843556, 12823.487060517662, 12842.807357889638, 12853.293068881114, 8211.671533138728, 8381.664733410717, 8399.70601028961, 8506.702265420805, 8521.10528394522, 7125.216226215112, 8774.692885748967, 8792.912070879209, 8793.977385201439, 8957.372983891066, 4014.437978682987, 4084.7140700150703, 4091.1204091120553, 4137.937930931039, 4156.189543039139, 1513.3870782333172, 1814.4695087451828, 1835.5671054403465, 1964.528513156832, 2014.0735261779453, 856.8629019356873, 906.9410488318238, 938.8967213606497, 987.160913223754, 1002.4837208837455, 16318.673626527403, 16865.91567042157, 19997.700034499496, 20473.283435079702, 20966.790332096483, 888.4669198480989, 960.2174228004154, 961.9230461563059, 966.2221911361421, 982.2878413150409, 10508.474576271308, 10916.454177291103, 10948.95984881435, 10957.397343146204, 10973.3964631946, 604364.0, 608248.0, 621220.0, 644549.327040379, 650318.0, 116106.25840612398, 128855.0, 132947.0, 135693.3215333917, 143568.0, 994.6319861651235, 1023.9641612543526, 1030.3251370352432, 1032.1794173631831, 1035.6012935020071, 15160.393584256506, 17803.287868485117, 18159.36442224516, 18689.252429903194, 18931.33740319082, 53636.0, 53636.46363536315, 54336.0, 54631.180532291255, 54734.17898731442, 78635.5957467961, 78737.0, 79040.60479697565, 79483.2051679499, 80192.19807801847, 7062.71749130029, 7585.0, 8026.678932842621, 8077.7576672699915, 8538.274246689101, 7765.844683106193, 8909.821803563762, 12678.302693351954, 12791.42438590247, 13020.088593798344, 10960.671179864617, 11177.217594768614, 11500.884991149982, 11527.88472115268, 11555.88444115548, 8639.740807775777, 9764.511774411392, 10721.731956701145, 11166.944165279121, 11410.714732131763, 11167.274127181707, 11315.603953861575, 11557.42212889369, 11806.527738890692, 11810.0, 5895.852603684943, 7533.8116547086765, 8222.83554328922, 8333.333333333198, 8607.569621518775, 10891.346519208873, 11148.331100134017, 11255.380954047607, 11518.0, 11609.6517104487], "value": 9132.46139233429, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1595.2821230446302, 1618.76527903454, 1832.8808627439216, 1953.9902300488486, 1960.7745109312416, 8536.871946920794, 8842.381033327669, 8990.460572365655, 8990.640374385033, 9009.32430067745, 7811.101723301822, 8100.189981001897, 8467.449615774985, 8708.085651006648, 8708.651653933835, 2452.9754702453015, 2502.224310463762, 2504.273760609421, 2505.6366826810104, 2506.9972011195578, 2173.5544213436206, 2254.627986382245, 2433.561958847407, 2512.886920088591, 2514.421683012908, 2386.331827088409, 2460.1266550374626, 2462.212092130519, 2470.641756945242, 2480.8139389545768, 2419.794317483016, 2480.9255722328357, 2489.0914816092177, 2489.116363690882, 2490.252924122774, 2443.8289319747623, 2459.4343301040863, 2468.691413573303, 2472.5425796227755, 2473.13440295895, 8774.912250877473, 8831.867521987175, 8846.46921184731, 8860.911390886138, 8874.645014199425, 5229.581633469352, 5399.028174928511, 5427.565794736412, 5427.62006659534, 5436.619436639437, 3428.2629234714655, 3528.788272703646, 3622.2574372253625, 3622.4747411625167, 3624.9093772655633, 952.2810278239954, 969.9427623889994, 1031.4533297352416, 1031.9019693129142, 1031.9019693129142, 11678.883211167864, 11694.2983420995, 11703.53185872564, 11710.882891171064, 11711.88288117125, 4764.118638051902, 4799.138309330957, 4871.196252618278, 4891.853244402602, 4906.386701662293, 8478.448900821428, 9915.900840991499, 9916.553755081039, 9926.15627671656, 9943.552540135712, 5345.38528069277, 5415.810446634349, 5427.104527752956, 5429.864253393618, 5439.374471935778, 4843.104025755176, 4857.53853383928, 4862.927056094161, 4961.578265847371, 4969.105560998983, 3979.144483935968, 3983.92032159361, 4039.4546736190828, 4079.73481723687, 4093.4473846030405, 1227.846519185102, 1267.702090008847, 1268.2390565660714, 1268.9746205075846, 1316.947322107105, 787.1223585701902, 847.6228078505129, 933.5472295936465, 945.2485274207022, 953.828310904044, 5189.714565698847, 5882.852928676817, 5941.792037278675, 6041.909371359433, 6184.721687524072, 833.2375876073326, 960.1023043454393, 974.4153507895348, 979.715882394112, 1010.4594042187395, 3891.20230352777, 3977.840886364571, 3996.960030399659, 4041.474608300902, 4063.756174629532, 10377.325473844327, 10667.359958402521, 10875.78248435027, 10886.891131088587, 10891.400972946563, 10707.518161682743, 10840.512176952056, 10843.240973131806, 10844.186685998582, 10849.67450976472, 8112.959435202786, 8294.917050829536, 8300.626471808782, 8307.169283071644, 8318.584070796556, 5914.260717410324, 5952.0, 6074.574779765374, 6112.816615501542, 6192.188592548161, 9389.389689670423, 9939.751506212402, 10058.8491172631, 10065.496725163859, 10067.748306292402, 9803.558839852081, 10088.19294456427, 10147.847782283125, 10185.185185185315, 10258.28192026551, 7395.630218489162, 7418.554886706815, 7530.28462296081, 7555.09338879338, 7561.6597253122645, 8108.310793582612, 8121.35029197651, 8198.50808951441, 8203.220694034057, 8203.876941845758, 7230.674619642024, 7408.518446300973, 7498.302598387206, 7518.736844210426, 7589.734359297398, 7230.168530619047, 7232.855342893218, 7289.708411663474, 7372.594507301936, 7421.332080112816, 7016.614086225102, 7036.155661320674, 7058.082449281561, 7058.470614704021, 7067.611281379368, 4217.388478670514, 4758.643101767312, 4961.42943561495, 4996.550310472075, 5029.84910452687, 4961.982007966587, 4982.800687972585, 5026.145555255543, 5082.186850104114, 5086.720230387365], "value": 4638.308758247263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.16582661541605, 300.8691219319596, 305.267358339984, 315.22926444842363, 323.6520740204281, 4810.687305325156, 5051.242313652954, 5349.5720342372615, 5356.758945847437, 5388.541973932211, 4244.172386384656, 5099.974500127494, 5143.3570803649545, 5223.477652234775, 5257.6330154159905, 3020.894268700596, 3944.4872166618443, 3948.3287841066945, 3983.741056831311, 4071.8982025449304, 3245.75656825739, 3720.5535335759746, 3838.692904567642, 3859.7298189126773, 4046.5548789633117, 3802.7338086333966, 4005.7596544207295, 4038.5759495253014, 4068.6338229559333, 4071.6335529802313, 4054.2702313583545, 4057.736247143941, 4068.294584101569, 4081.244969680589, 4085.285075111873, 3410.488426735985, 3457.299183252725, 3507.438809790421, 3511.0, 3514.2620049789575, 4494.741472387736, 4741.885656870632, 5043.209660570481, 5062.341895553592, 5084.466131056222, 4382.802773875152, 4585.633149348044, 4585.679002469828, 4593.0, 4623.606993405565, 4770.878843471782, 5177.0, 5543.0, 5609.859753506154, 5622.522085622727, 3855.363864962278, 3862.517185351831, 3869.3808990561374, 3874.3219936511277, 3880.5149356330458, 5267.947320526784, 5618.4662457066515, 5861.120831875211, 5908.231929849135, 5910.290765108214, 3272.985374533902, 3618.4029635109914, 3660.9450858237146, 3929.5677475477673, 3937.9606203938165, 3532.1346270163463, 3649.39784935488, 3702.33357995558, 3989.4215338776294, 3995.161016186575, 4064.878053658395, 4291.592298731678, 4431.955680443154, 4451.666125040635, 4459.977700111479, 4737.8341758038305, 4796.880077998008, 5545.916811247835, 5573.944260557424, 5595.552355811526, 1145.9457299284677, 1253.7053792358788, 1269.803180507023, 1275.8405199350082, 1276.6021206778562, 828.9088200297963, 841.6071401829981, 867.5705525764787, 871.7210492642421, 881.4931414436596, 4325.275076701658, 4477.3731677565165, 4805.038992201574, 4884.8290309838985, 4888.846693882426, 4671.532846715301, 4781.282807578821, 4844.394450693663, 5316.93661267748, 5508.394076651565, 4464.53122422141, 4496.797644106023, 4798.7840547175465, 4953.678010929278, 4955.306257124005, 2602.53154432204, 2626.540355437791, 2690.556058250406, 2728.399752054545, 2735.2478068530913, 5233.528982391603, 5249.086756849985, 5255.5795536357, 5624.775008999594, 5699.202111704365, 4999.675151054777, 5324.41431442541, 5645.2096706461125, 5653.519450846642, 5776.537876969833, 4997.900041999213, 5284.504115719762, 5772.740226689809, 5799.971000144972, 5815.244018277682, 4801.327814106098, 4876.878078048077, 5239.502247286654, 5416.512513873613, 5475.972620136874, 5275.366955965309, 5647.745851436777, 5766.4233576642, 5790.623609465371, 5802.65184088956, 5256.395514515727, 6006.309274433322, 6034.7284372202475, 6052.334243233329, 6078.939210607838, 3369.3935091683734, 3771.2268984857487, 3895.889671443625, 3899.68802495794, 3911.5697273299347, 5485.9725701371235, 5627.0434026214825, 5651.971740141273, 5795.33353664334, 5854.443827836349, 5159.845204643867, 5215.843524694265, 5611.214429979887, 5643.673736671961, 5656.925184214986, 4723.574878260973, 5497.972510137423, 5597.524210442158, 5607.0, 5613.494785469327, 4958.107540642719, 5465.480779325958, 5466.316710411199, 5485.835424937259, 5536.501714845684, 4732.597729193058, 5072.289879416959, 5337.452138879681, 5600.395815945605, 5613.326400831763, 4937.876553086202, 5145.45972672864, 5284.9735751320995, 5439.211314359475, 5521.3926468087675], "value": 3995.0283127264524, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.9238787539433, 729.6351824087952, 750.0998801438276, 753.2053683364052, 779.8105747492754, 14579.489717859877, 15144.469943551978, 15177.937544371898, 15185.544433666993, 15206.695866082671, 13699.931500342509, 13997.790033149486, 14058.226797526131, 14058.859411405883, 14069.296535173251, 858.159004175908, 877.4910551879894, 888.7778055486128, 909.8498747706637, 932.0772435289049, 892.6117139044536, 899.6221586933503, 905.9320550958671, 906.5920335848862, 908.7001289574424, 958.3483231402644, 958.8513780364054, 959.3859929645033, 959.9808003839919, 961.0437614573509, 948.9952550237274, 949.1599934058339, 951.8524628682566, 952.1288021460356, 952.9332946693734, 709.1915216652968, 713.0730050933818, 713.4043074033189, 714.5105602662193, 715.2525610736792, 12901.483940642363, 12931.224126552437, 12940.0, 12941.352932353439, 12954.546590869368, 10846.72883177919, 11062.944685276521, 11146.777064458665, 11215.214934954556, 11249.0, 9280.675176368863, 9400.747198007472, 9429.669961551313, 9694.612215511372, 10177.491125443772, 3402.761806673534, 3405.404054290489, 3413.7098346640564, 3426.1777173478436, 3453.533772940671, 71750.64124679395, 71798.28201717968, 72152.63923680349, 74835.87746183811, 74946.87579686363, 6859.3140685932, 8096.271335579826, 8163.918360816435, 8170.550619715973, 8211.260986511243, 19058.0, 19063.714044289347, 19090.14094365757, 19114.617707645768, 19116.61766764657, 7245.021922040457, 7248.710051597983, 7281.817954551178, 7465.962670186723, 7494.6627401767055, 3867.2458870519977, 3955.723099383016, 3970.3845903884944, 3982.900427489278, 3999.5400528939545, 3064.387122575449, 3523.5419395478425, 3635.818209089535, 3657.140571965586, 3681.7054635629083, 527.5041461026623, 634.1660716158237, 639.309545690653, 643.2827397451902, 645.7998020613625, 557.6654007595491, 561.9269494965629, 581.383733242764, 589.8495883549667, 606.1483615520114, 8019.238172373615, 8100.797480062928, 8154.103048664642, 8174.591270436574, 8293.95853020743, 498.60111910471477, 528.8022628745894, 592.0940960330701, 594.3759052994338, 608.9391060893964, 3743.3636281832155, 3835.2521258354354, 3876.9418458723144, 3946.427767973685, 3953.011747063234, 81420.0, 81482.0, 82503.5874820634, 83287.5835620818, 83441.0, 71255.0, 71641.92537111948, 72560.0, 72936.63531682307, 72995.63502182561, 64513.03230451547, 64659.0, 64731.0, 64851.67574162099, 65419.0, 7497.437692173218, 7553.848923021619, 7601.885971710318, 7747.612619368896, 8006.1993800619475, 40837.795811020755, 41179.38230926479, 41272.968175795846, 41812.581874182084, 42241.0, 53883.73058134684, 56629.71685141548, 58254.2523724289, 58489.348607278014, 58890.0, 8616.913830861862, 10068.613291888805, 10255.384676919408, 10435.739106522095, 10935.343879367263, 10865.076468500267, 10945.890541094486, 11094.278871873303, 11147.665570032912, 11491.48288327044, 10033.949830250802, 10054.989002199736, 10399.32404393712, 10659.626913058006, 10705.518251678848, 9734.9026509734, 9983.60065597397, 10286.177105831664, 10361.0, 10408.739781505523, 10243.282970192015, 10459.738506537398, 10559.260851740304, 10563.419011954416, 10607.310524815863, 8641.697540586048, 8859.778505537413, 8879.600417981077, 8968.551572421484, 9179.31155163352, 8855.3801233913, 8914.821703565764, 8934.329925255503, 8945.59744811498, 8991.28069754405], "value": 6507.2394769309285, "unit": "score"}], "name": "dromaeo_css", "value": 7021.119040514589, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 112.0, 113.0, 112.0, 113.0, 111.0, 112.0, 111.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 173.0, 191.0, 119.0, 141.0, 138.0, 126.0, 202.0, 139.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 186.0, 184.0, 182.0, 187.0, 182.0, 182.0, 185.0, 186.0, 185.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 59.0, 107.0, 73.0, 84.0, 79.0, 58.0, 111.0, 57.0], "value": 78.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 101.0, 79.0, 79.0, 78.0, 102.0, 82.0, 79.0, 101.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 83.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0, 91.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 83.0, 83.0, 84.0, 87.0, 85.0, 86.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 58.0, 59.0, 60.0, 59.0, 77.0, 61.0, 59.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 76.0, 74.0, 72.0, 70.0, 68.0, 67.0, 69.0, 68.0, 70.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 85.0, 85.0, 84.0, 85.0, 86.0, 93.0, 107.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 138.0, 137.0, 138.0, 134.0, 135.0, 137.0, 176.0, 138.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 165.0, 170.0, 168.0, 167.0, 168.0, 171.0, 204.0, 166.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 79.0, 84.0, 86.0, 68.0, 68.0, 65.0, 66.0, 66.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1479.7, "alertThreshold": 2.0}]} 00:45:28 INFO - Return code: 0 00:45:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 00:45:28 ERROR - # TBPL SUCCESS # 00:45:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:45:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:45:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:45:28 INFO - 2016-05-09 00:45:28.866 screenresolution[1680:9753] starting screenresolution argv=screenresolution get 00:45:28 INFO - 2016-05-09 00:45:28.869 screenresolution[1680:9753] Display 0: 1600x1200x32@60 00:45:28 INFO - 2016-05-09 00:45:28.875 screenresolution[1681:9756] starting screenresolution argv=screenresolution list 00:45:28 INFO - Available Modes on Display 0 00:45:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:45:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:45:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:45:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:45:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:45:29 INFO - Intel Iris: 00:45:29 INFO - Chipset Model: Intel Iris 00:45:29 INFO - Type: GPU 00:45:29 INFO - Bus: Built-In 00:45:29 INFO - VRAM (Dynamic, Max): 1536 MB 00:45:29 INFO - Vendor: Intel (0x8086) 00:45:29 INFO - Device ID: 0x0a2e 00:45:29 INFO - Revision ID: 0x0009 00:45:29 INFO - Displays: 00:45:29 INFO - Display: 00:45:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:45:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:45:29 INFO - Display Serial Number: 1600x1200 60 00:45:29 INFO - Main Display: Yes 00:45:29 INFO - Mirror: Off 00:45:29 INFO - Online: Yes 00:45:29 INFO - Rotation: Supported 00:45:29 INFO - Return code: 0 00:45:29 INFO - Running post-action listener: _resource_record_post_action 00:45:29 INFO - ##### 00:45:29 INFO - ##### Finished run-tests step (success) 00:45:29 INFO - ##### 00:45:29 INFO - Running post-run listener: _resource_record_post_run 00:45:30 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 37950976; Write bytes: 696132096; Read time: 397; Write time: 2173 00:45:30 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382231040; Write bytes: 376416256; Read time: 28440; Write time: 697 00:45:30 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 36996608; Write bytes: 296925696; Read time: 382; Write time: 1429 00:45:30 INFO - Running post-run listener: _upload_blobber_files 00:45:30 INFO - Blob upload gear active. 00:45:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:45:30 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 00:45:30 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'] 00:45:30 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 00:45:30 INFO - (blobuploader) - INFO - Open directory for files ... 00:45:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:45:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:45:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:45:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:45:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:45:31 INFO - (blobuploader) - INFO - Done attempting. 00:45:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:45:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:45:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:45:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:45:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:45:32 INFO - (blobuploader) - INFO - Done attempting. 00:45:32 INFO - (blobuploader) - INFO - Iteration through files over. 00:45:32 INFO - Return code: 0 00:45:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:45:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:45:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b2d6b063eb2284e547c4c7cc7631c3d0a3abc63a14aa65356f6c77158ee09245caafa454ffb6193eef9501b23a56d36e0bd38aa5b77472d76e592cb0560b0b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0dbbd4ce1bf86e83ac7d2cdd0f7ee26afa70b4f895c3a454143106d137307cc69e62894780106a7d5d17e98ae7422e4ca044854e06c0c991624230bc57fc5b81"} 00:45:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:45:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:45:32 INFO - Contents: 00:45:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b2d6b063eb2284e547c4c7cc7631c3d0a3abc63a14aa65356f6c77158ee09245caafa454ffb6193eef9501b23a56d36e0bd38aa5b77472d76e592cb0560b0b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0dbbd4ce1bf86e83ac7d2cdd0f7ee26afa70b4f895c3a454143106d137307cc69e62894780106a7d5d17e98ae7422e4ca044854e06c0c991624230bc57fc5b81"} 00:45:32 INFO - Running post-run listener: copy_logs_to_upload_dir 00:45:32 INFO - Copying logs to upload dir... 00:45:32 INFO - mkdir: /builds/slave/test/build/upload/logs 00:45:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.777071 ========= master_lag: 3.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-09 00:45:36.215208) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 00:45:36.219971) ========= 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.942lBzFEpv/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.yZ25DLEE96/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b2d6b063eb2284e547c4c7cc7631c3d0a3abc63a14aa65356f6c77158ee09245caafa454ffb6193eef9501b23a56d36e0bd38aa5b77472d76e592cb0560b0b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0dbbd4ce1bf86e83ac7d2cdd0f7ee26afa70b4f895c3a454143106d137307cc69e62894780106a7d5d17e98ae7422e4ca044854e06c0c991624230bc57fc5b81"} build_url:https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011505 build_url: 'https://queue.taskcluster.net/v1/task/DYfzLBCBTuimzmybRB_gcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b2d6b063eb2284e547c4c7cc7631c3d0a3abc63a14aa65356f6c77158ee09245caafa454ffb6193eef9501b23a56d36e0bd38aa5b77472d76e592cb0560b0b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0dbbd4ce1bf86e83ac7d2cdd0f7ee26afa70b4f895c3a454143106d137307cc69e62894780106a7d5d17e98ae7422e4ca044854e06c0c991624230bc57fc5b81"}' ========= master_lag: 0.12 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 00:45:36.347661) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:45:36.348049) ========= 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.942lBzFEpv/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.yZ25DLEE96/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005179 ========= master_lag: 0.43 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 00:45:36.778987) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 00:45:36.779285) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 00:45:36.832506) ========= ========= Total master_lag: 4.13 =========